You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/03/10 22:48:45 UTC
[JENKINS-EA] Lucene-Solr-8.x-Windows (64bit/jdk-13-ea+8) - Build #
80 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/80/
Java: 64bit/jdk-13-ea+8 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: org.apache.solr.BasicFunctionalityTest.testDateMath
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([BA49FD0FB46FD621:A5CAD632A6607522]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:930)
at org.apache.solr.BasicFunctionalityTest.testDateMath(BasicFunctionalityTest.java:926)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=*[count(//doc)=2]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="3" start="0"><doc><str name="id">2</str><arr name="range_facet_l"><long>2</long></arr><int name="id_i1">2</int><arr name="range_facet_l_dv"><long>2</long></arr><arr name="range_facet_i_dv"><int>2</int></arr><date name="bday">2019-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T21:49:24.407Z</date></arr><str name="_root_">2</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634432</long><int name="intDvoDefault">42</int></doc><doc><str name="id">5</str><arr name="range_facet_l"><long>5</long></arr><int name="id_i1">5</int><arr name="range_facet_l_dv"><long>5</long></arr><arr name="range_facet_i_dv"><int>5</int></arr><date name="bday">2019-03-10T22:19:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T22:19:24.407Z</date></arr><str name="_root_">5</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634435</long><int name="intDvoDefault">42</int></doc><doc><str name="id">6</str><arr name="range_facet_l"><long>6</long></arr><int name="id_i1">6</int><arr name="range_facet_l_dv"><long>6</long></arr><arr name="range_facet_i_dv"><int>6</int></arr><date name="bday">2021-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2021-03-10T21:49:24.407Z</date></arr><str name="_root_">6</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634436</long><int name="intDvoDefault">42</int></doc></result>
</response>
request was:q=bday:[NOW+TO+*]&wt=xml
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:923)
... 39 more
FAILED: org.apache.solr.cloud.ActionThrottleTest.testAZeroNanoTimeReturnInWait
Error Message:
990ms
Stack Trace:
java.lang.AssertionError: 990ms
at __randomizedtesting.SeedInfo.seed([BA49FD0FB46FD621:7922063D002E2BC2]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.ActionThrottleTest.testAZeroNanoTimeReturnInWait(ActionThrottleTest.java:113)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
Build Log:
[...truncated 13688 lines...]
[junit4] Suite: org.apache.solr.cloud.ActionThrottleTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ActionThrottleTest_BA49FD0FB46FD621-001\init-core-data-001
[junit4] 2> 1145258 WARN (SUITE-ActionThrottleTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=27 numCloses=27
[junit4] 2> 1145258 INFO (SUITE-ActionThrottleTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1145258 INFO (SUITE-ActionThrottleTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1145258 INFO (SUITE-ActionThrottleTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1145258 INFO (TEST-ActionThrottleTest.testCreateNewThrottleWithLastValue-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNewThrottleWithLastValue
[junit4] 2> 1145258 INFO (TEST-ActionThrottleTest.testCreateNewThrottleWithLastValue-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNewThrottleWithLastValue
[junit4] 2> 1145258 INFO (TEST-ActionThrottleTest.testBasics-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasics
[junit4] 2> 1145367 INFO (TEST-ActionThrottleTest.testBasics-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.ActionThrottle Throttling test attempts - waiting for 904ms
[junit4] 2> 1146273 INFO (TEST-ActionThrottleTest.testBasics-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.ActionThrottle Throttling test attempts - waiting for 1000ms
[junit4] 2> 1147798 INFO (TEST-ActionThrottleTest.testBasics-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBasics
[junit4] 2> 1147814 INFO (TEST-ActionThrottleTest.testAZeroNanoTimeReturnInWait-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAZeroNanoTimeReturnInWait
[junit4] 2> 1147814 INFO (TEST-ActionThrottleTest.testAZeroNanoTimeReturnInWait-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.ActionThrottle Throttling test attempts - waiting for 1000ms
[junit4] 2> 1148816 INFO (TEST-ActionThrottleTest.testAZeroNanoTimeReturnInWait-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAZeroNanoTimeReturnInWait
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ActionThrottleTest -Dtests.method=testAZeroNanoTimeReturnInWait -Dtests.seed=BA49FD0FB46FD621 -Dtests.slow=true -Dtests.locale=hy -Dtests.timezone=America/Atka -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.02s J1 | ActionThrottleTest.testAZeroNanoTimeReturnInWait <<<
[junit4] > Throwable #1: java.lang.AssertionError: 990ms
[junit4] > at __randomizedtesting.SeedInfo.seed([BA49FD0FB46FD621:7922063D002E2BC2]:0)
[junit4] > at org.apache.solr.cloud.ActionThrottleTest.testAZeroNanoTimeReturnInWait(ActionThrottleTest.java:113)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ActionThrottleTest_BA49FD0FB46FD621-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@382ee539), locale=hy, timezone=America/Atka
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 13-ea (64-bit)/cpus=3,threads=1,free=252824728,total=504365056
[junit4] 2> NOTE: All tests run in this JVM: [TestLeaderElectionWithEmptyReplica, TestNumericTerms32, TestSlowCompositeReaderWrapper, V2ApiIntegrationTest, TestRequestStatusCollectionAPI, PeerSyncTest, DocValuesNotIndexedTest, TestQueryTypes, TestRandomFlRTGCloud, TestReplicationHandler, TestCoreDiscovery, XmlUpdateRequestHandlerTest, IgnoreLargeDocumentProcessorFactoryTest, TestRecoveryHdfs, SuggesterTest, MetricsConfigTest, TestShardHandlerFactory, TestRawTransformer, HttpPartitionTest, TestManagedSchemaThreadSafety, DistanceFunctionTest, TestLocalFSCloudBackupRestore, SystemInfoHandlerTest, DateFieldTest, SoftAutoCommitTest, TestSimGenericDistributedQueue, BadCopyFieldTest, BooleanFieldTest, TestCSVResponseWriter, TestSchemaVersionResource, CloudExitableDirectoryReaderTest, ClusterStateUpdateTest, TestStressCloudBlindAtomicUpdates, CustomTermsComponentTest, TestExportWriter, TestStandardQParsers, CollectionReloadTest, MultiSolrCloudTestCaseTest, TestSmileRequest, MBeansHandlerTest, SpatialHeatmapFacetsTest, TestConfigSets, SimplePostToolTest, SolrRrdBackendFactoryTest, AnalysisErrorHandlingTest, TestHighlightDedupGrouping, TestSimDistribStateManager, TestRestoreCore, BasicDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, TestNRTOpen, TestLuceneIndexBackCompat, MetricTriggerTest, ChaosMonkeySafeLeaderTest, AliasIntegrationTest, ZkControllerTest, AutoAddReplicasPlanActionTest, SmileWriterTest, TestDeleteCollectionOnDownNodes, LeaderElectionTest, TestCSVLoader, OverseerModifyCollectionTest, TestCloudJSONFacetSKG, SolrCLIZkUtilsTest, TestUninvertingReader, TestRandomDVFaceting, HdfsRecoverLeaseTest, TestCoreBackup, TestSolrQueryParser, DocumentBuilderTest, TestRuleBasedAuthorizationPlugin, TestAuthorizationFramework, ImplicitSnitchTest, TestInPlaceUpdatesDistrib, TestCopyFieldCollectionResource, ShardsWhitelistTest, TestAnalyzeInfixSuggestions, RulesTest, SolrCloudReportersTest, TestLockTree, HealthCheckHandlerTest, NodeLostTriggerTest, SSLMigrationTest, TestSolrCoreSnapshots, BigEndianAscendingWordDeserializerTest, TestJsonFacetsWithNestedObjects, SortSpecParsingTest, DistributedQueueTest, TestExclusionRuleCollectionAccess, DistributedFacetPivotLongTailTest, TestTolerantUpdateProcessorCloud, TestSearcherReuse, HdfsUnloadDistributedZkTest, DistributedFacetExistsSmallTest, TestSubQueryTransformerCrossCore, TestDelegationWithHadoopAuth, TestValueSourceCache, BufferStoreTest, BlockJoinFacetSimpleTest, TestInPlaceUpdatesStandalone, DirectoryFactoryTest, CdcrReplicationHandlerTest, TriggerIntegrationTest, DistribDocExpirationUpdateProcessorTest, TestManagedSynonymFilterFactory, CdcrVersionReplicationTest, TestDistributedMissingSort, ActionThrottleTest]
[junit4] Completed [261/844 (1!)] on J1 in 3.82s, 3 tests, 1 failure <<< FAILURES!
[...truncated 35 lines...]
[junit4] Suite: org.apache.solr.BasicFunctionalityTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.BasicFunctionalityTest_BA49FD0FB46FD621-001\init-core-data-001
[junit4] 2> 1195236 WARN (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 1195236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1195236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1195236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1195236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1195236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-8.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-8.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1195251 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1195267 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1195346 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1195392 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1195392 WARN (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@720b8dd5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1195392 WARN (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@15a8d86a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1195423 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1195423 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1195423 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6998e8a6
[junit4] 2> 1195439 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6998e8a6
[junit4] 2> 1195439 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6998e8a6
[junit4] 2> 1195439 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-8.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-8.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1195455 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1195486 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1195548 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1195548 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 1195548 INFO (coreLoadExecutor-6653-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@6998e8a6
[junit4] 2> 1195548 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.BasicFunctionalityTest_BA49FD0FB46FD621-001\init-core-data-001\]
[junit4] 2> 1195564 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=55.85546875, floorSegmentMB=2.0576171875, forceMergeDeletesPctAllowed=27.563366632583996, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8022381429766747, deletesPctAllowed=27.227080846736087
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@282041c2[collection1] main]
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1195626 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1195658 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1195658 INFO (coreLoadExecutor-6653-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627656882078351360
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1195658 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@282041c2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFieldBoost
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1627656882078351361), 2 (1627656882078351362)]} 0 1
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882078351363,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882078351363
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@184e4766[collection1] main]
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195658 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@184e4766[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C2:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564360}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1195658 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=text:hello&qt=&start=0&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 1195673 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=text:hello&wt=xml&debugQuery=true} hits=2 status=0 QTime=0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testFieldBoost-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testFieldBoost
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDefaultFieldValues
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ad8d06c[collection1] realtime]
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4055 (1627656882095128576)]} 0 0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4056 (1627656882095128577)]} 0 0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4057 (1627656882095128578)]} 0 0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882095128579,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882095128579
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2535e8de[collection1] main]
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195674 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2535e8de[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.1.0):C3:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564376}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=timestamp:[NOW-10MINUTES+TO+NOW]&qt=&start=0&rows=20&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=multiDefault:muLti-Default&qt=&start=0&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=multiDefault:a&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=intDefault:42&qt=&start=0&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=intDefault:[3+TO+5]&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testDefaultFieldValues-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDefaultFieldValues
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testXMLWriter-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testXMLWriter
[junit4] 2> 1195674 INFO (TEST-BasicFunctionalityTest.testXMLWriter-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testXMLWriter
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testRequestHandlerBaseException-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRequestHandlerBaseException
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testRequestHandlerBaseException-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching ignore_exception
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testRequestHandlerBaseException-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={wt=xml} status=500 QTime=0
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testRequestHandlerBaseException-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRequestHandlerBaseException
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDateMath
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@53c79311[collection1] realtime]
[junit4] 2> 1195690 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1627656882111905792)]} 0 1
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2 (1627656882127634432)]} 0 0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3 (1627656882127634433)]} 0 0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4 (1627656882127634434)]} 0 0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5 (1627656882127634435)]} 0 0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6 (1627656882127634436)]} 0 0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882127634437,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882127634437
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a9b2cd5[collection1] main]
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195705 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a9b2cd5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.1.0):C6:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564407}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:1976-07-04T12\:08\:56.45Z/SECOND%2B235MILLIS&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:1976-07-04T12\:08\:56.123Z/MINUTE%2B56SECONDS%2B235MILLIS&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:"1976-07-04T12:08:56.45Z/SECOND%2B235MILLIS"&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:"1976-07-04T12:08:56.123Z/MINUTE%2B56SECONDS%2B235MILLIS"&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicFunctionalityTest -Dtests.method=testDateMath -Dtests.seed=BA49FD0FB46FD621 -Dtests.slow=true -Dtests.locale=sq-XK -Dtests.timezone=Pacific/Port_Moresby -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.02s J1 | BasicFunctionalityTest.testDateMath <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([BA49FD0FB46FD621:A5CAD632A6607522]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:930)
[junit4] > at org.apache.solr.BasicFunctionalityTest.testDateMath(BasicFunctionalityTest.java:926)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=*[count(//doc)=2]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="3" start="0"><doc><str name="id">2</str><arr name="range_facet_l"><long>2</long></arr><int name="id_i1">2</int><arr name="range_facet_l_dv"><long>2</long></arr><arr name="range_facet_i_dv"><int>2</int></arr><date name="bday">2019-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T21:49:24.407Z</date></arr><str name="_root_">2</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634432</long><int name="intDvoDefault">42</int></doc><doc><str name="id">5</str><arr name="range_facet_l"><long>5</long></arr><int name="id_i1">5</int><arr name="range_facet_l_dv"><long>5</long></arr><arr name="range_facet_i_dv"><int>5</int></arr><date name="bday">2019-03-10T22:19:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T22:19:24.407Z</date></arr><str name="_root_">5</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634435</long><int name="intDvoDefault">42</int></doc><doc><str name="id">6</str><arr name="range_facet_l"><long>6</long></arr><int name="id_i1">6</int><arr name="range_facet_l_dv"><long>6</long></arr><arr name="range_facet_i_dv"><int>6</int></arr><date name="bday">2021-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2021-03-10T21:49:24.407Z</date></arr><str name="_root_">6</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634436</long><int name="intDvoDefault">42</int></doc></result>
[junit4] > </response>
[junit4] > request was:q=bday:[NOW+TO+*]&wt=xml
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:923)
[junit4] > ... 39 more
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!term+f%3Dbday}1976-07-04T12:08:56.45Z/SECOND%2B235MILLIS&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!term+f%3Dbday}1976-07-04T12:08:56.123Z/MINUTE%2B56SECONDS%2B235MILLIS&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!term+f%3Dbday}1976-07-04T12:08:56.235Z&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!term+f%3Dbday}1976-07-04T12:08:56.235Z%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:"1976-07-04T12:08:56.235Z%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS%2B1SECONDS-1SECONDS"&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:[*+TO+1976-07-04T12:08:56.235Z/SECOND]&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:[*+TO+1976-07-04T12:08:56.235Z/SECOND%2B1SECOND]&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:[1976-07-04T12:08:56.235Z/SECOND+TO+1976-07-04T12:08:56.235Z/SECOND%2B1SECOND]&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:[1976-07-04T12:08:56.235Z/MINUTE%2B1MINUTE+TO+*]&wt=xml} hits=5 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:[*+TO+NOW]&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bday:[NOW+TO+*]&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 1195705 ERROR (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=*[count(//doc)=2]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="3" start="0"><doc><str name="id">2</str><arr name="range_facet_l"><long>2</long></arr><int name="id_i1">2</int><arr name="range_facet_l_dv"><long>2</long></arr><arr name="range_facet_i_dv"><int>2</int></arr><date name="bday">2019-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T21:49:24.407Z</date></arr><str name="_root_">2</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634432</long><int name="intDvoDefault">42</int></doc><doc><str name="id">5</str><arr name="range_facet_l"><long>5</long></arr><int name="id_i1">5</int><arr name="range_facet_l_dv"><long>5</long></arr><arr name="range_facet_i_dv"><int>5</int></arr><date name="bday">2019-03-10T22:19:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T22:19:24.407Z</date></arr><str name="_root_">5</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634435</long><int name="intDvoDefault">42</int></doc><doc><str name="id">6</str><arr name="range_facet_l"><long>6</long></arr><int name="id_i1">6</int><arr name="range_facet_l_dv"><long>6</long></arr><arr name="range_facet_i_dv"><int>6</int></arr><date name="bday">2021-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2021-03-10T21:49:24.407Z</date></arr><str name="_root_">6</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634436</long><int name="intDvoDefault">42</int></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=bday:[NOW+TO+*]&wt=xml
[junit4] 2> 1195705 ERROR (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=bday:[NOW+TO+*]&wt=xml:java.lang.RuntimeException: REQUEST FAILED: xpath=*[count(//doc)=2]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="3" start="0"><doc><str name="id">2</str><arr name="range_facet_l"><long>2</long></arr><int name="id_i1">2</int><arr name="range_facet_l_dv"><long>2</long></arr><arr name="range_facet_i_dv"><int>2</int></arr><date name="bday">2019-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T21:49:24.407Z</date></arr><str name="_root_">2</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634432</long><int name="intDvoDefault">42</int></doc><doc><str name="id">5</str><arr name="range_facet_l"><long>5</long></arr><int name="id_i1">5</int><arr name="range_facet_l_dv"><long>5</long></arr><arr name="range_facet_i_dv"><int>5</int></arr><date name="bday">2019-03-10T22:19:24.407Z</date><arr name="range_facet_dt_dv"><date>2019-03-10T22:19:24.407Z</date></arr><str name="_root_">5</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634435</long><int name="intDvoDefault">42</int></doc><doc><str name="id">6</str><arr name="range_facet_l"><long>6</long></arr><int name="id_i1">6</int><arr name="range_facet_l_dv"><long>6</long></arr><arr name="range_facet_i_dv"><int>6</int></arr><date name="bday">2021-03-10T21:49:24.407Z</date><arr name="range_facet_dt_dv"><date>2021-03-10T21:49:24.407Z</date></arr><str name="_root_">6</str><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-03-10T21:49:24.407Z</date><long name="_version_">1627656882127634436</long><int name="intDvoDefault">42</int></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=bday:[NOW+TO+*]&wt=xml
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:923)
[junit4] 2> at org.apache.solr.BasicFunctionalityTest.testDateMath(BasicFunctionalityTest.java:926)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2>
[junit4] 2> 1195705 INFO (TEST-BasicFunctionalityTest.testDateMath-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDateMath
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAbuseOfSort
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9999991 (1627656882143363072)]} 0 0
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9999992 (1627656882143363073)]} 0 0
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882143363074,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882143363074
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@44b00e14[collection1] main]
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195720 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44b00e14[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.1.0):C6:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564407}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.1.0):C2:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564422}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching sortabuse
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&sort=sortabuse_not_uninvertible+asc&wt=xml} status=400 QTime=0
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 Ignoring exception matching sortabuse
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching sortabuse
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&sort=sortabuse_t+asc&wt=xml} status=400 QTime=0
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 Ignoring exception matching sortabuse
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testAbuseOfSort-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAbuseOfSort
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNonTrivialDeleteByQuery
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@736b1d00[collection1] realtime]
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[101 (1627656882143363075)]} 0 1
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[102 (1627656882143363076)]} 0 0
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[103 (1627656882143363077)]} 0 0
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[104 (1627656882143363078)]} 0 0
[junit4] 2> 1195720 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[105 (1627656882143363079)]} 0 0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[106 (1627656882160140288)]} 0 0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882160140289,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882160140289
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@58bc8e0b[collection1] main]
[junit4] 2> 1195736 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@58bc8e0b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(8.1.0):C6:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564438}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:[100+TO+110]&qt=&start=0&rows=20&version=2.2} hits=6 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=6 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=text:red&qt=&start=0&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=-text:red&qt=&start=0&rows=20&version=2.2} hits=4 status=0 QTime=2
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=text:grape&qt=&start=0&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=-text:grape&qt=&start=0&rows=20&version=2.2} hits=4 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=-text:red+-text:grape&qt=&start=0&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!lucene+q.op%3DAND+df%3Dtext}grape+green&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=-_val_:"{!lucene+q.op%3DAND+df%3Dtext}grape+green"&qt=&start=0&rows=20&version=2.2} hits=5 status=0 QTime=0
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=-*:* (-1627656882160140290)} 0 1
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882160140291,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195736 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=6 status=0 QTime=0
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18568fe0[collection1] realtime]
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=-text:grape -text:red (-1627656882175868928)} 0 4
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882175868929,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882175868929
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d8fe332[collection1] main]
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195751 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d8fe332[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(8.1.0):C6/2:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564438}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1)))}
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=4 status=0 QTime=0
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@788f517[collection1] realtime]
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery={!term f=id}106 (-1627656882175868930)} 0 2
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882175868931,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882175868931
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@667ca18d[collection1] main]
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195751 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@667ca18d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(8.1.0):C6/3:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564438}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=2)))}
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 1195751 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c645396[collection1] realtime]
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=-_val_:"{!lucene q.op=AND df=text}grape green" (-1627656882175868932)} 0 1
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882192646144,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882192646144
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@41286bb2[collection1] main]
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195767 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41286bb2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(8.1.0):C6/5:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564438}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=3)))}
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7141f492[collection1] realtime]
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=-text:doesnotexist (-1627656882192646145)} 0 1
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882192646146,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882192646146
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@68b44f4a[collection1] main]
[junit4] 2> 1195767 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68b44f4a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testNonTrivialDeleteByQuery-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNonTrivialDeleteByQuery
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleUpdatesPerAdd
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1829e571[collection1] realtime]
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1627656882192646147), 2 (1627656882192646148)]} 0 0
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882192646149,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195767 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882192646149
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11339de9[collection1] main]
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195783 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11339de9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.1.0):C2:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564485}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:[0+TO+99]&qt=&start=0&rows=20&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testMultipleUpdatesPerAdd-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleUpdatesPerAdd
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIgnoredFields
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[42 (1627656882209423360)]} 0 1
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882209423361,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7031fd40 commitCommandVersion:1627656882209423361
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@68130dea[collection1] main]
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1195783 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68130dea[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.1.0):C2:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564485}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.1.0):C1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564485}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bar_ignored:yo+id:42&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 1195783 INFO (TEST-BasicFunctionalityTest.testIgnoredFields-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testIgnoredFields
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testClientErrorOnMalformedNumbers
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{} 0 0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_ti:NOT_A_NUMBER&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_ti:[10+TO+NOT_A_NUMBER]&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{} 0 0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_tf:NOT_A_NUMBER&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_tf:[10+TO+NOT_A_NUMBER]&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{} 0 0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_td:NOT_A_NUMBER&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_td:[10+TO+NOT_A_NUMBER]&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{} 0 0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_tl:NOT_A_NUMBER&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching NOT_A_NUMBER
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=malformed_tl:[10+TO+NOT_A_NUMBER]&wt=xml} status=400 QTime=0
[junit4] 2> 1195799 INFO (TEST-BasicFunctionalityTest.testClientErrorOnMalformedNumbers-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{} 0 0
[junit4] 2> 11957
[...truncated too long message...]
ip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627656882651922433,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1196205 INFO (TEST-BasicFunctionalityTest.testHTMLStrip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@f58110 commitCommandVersion:1627656882651922433
[junit4] 2> 1196205 INFO (TEST-BasicFunctionalityTest.testHTMLStrip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7487e222[collection1] main]
[junit4] 2> 1196205 INFO (TEST-BasicFunctionalityTest.testHTMLStrip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1196205 INFO (searcherExecutor-6654-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7487e222[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_g(8.1.0):C2:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564844}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_h(8.1.0):C6:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564860}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_i(8.1.0):C2:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=10.0, timestamp=1552254564907}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1196205 INFO (TEST-BasicFunctionalityTest.testHTMLStrip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1196221 INFO (TEST-BasicFunctionalityTest.testHTMLStrip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=HTMLwhitetok:A%26#66;C&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1196221 INFO (TEST-BasicFunctionalityTest.testHTMLStrip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=HTMLwhitetok:%26#65;BC&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 1196221 INFO (TEST-BasicFunctionalityTest.testHTMLStrip-seed#[BA49FD0FB46FD621]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHTMLStrip
[junit4] 2> 1196221 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1196221 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2039372125
[junit4] 2> 1196221 INFO (coreCloseExecutor-6659-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@61b63292
[junit4] 2> 1196221 INFO (coreCloseExecutor-6659-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=61b63292
[junit4] 2> 1196221 INFO (coreCloseExecutor-6659-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@264fc1e4: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6f9f5fa
[junit4] 2> 1196221 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1196221 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4ba1ea40: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1f5d5deb
[junit4] 2> 1196236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1196236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@432a129e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@526490ac
[junit4] 2> 1196236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1196236 INFO (SUITE-BasicFunctionalityTest-seed#[BA49FD0FB46FD621]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1dd6c689: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3c6147bc
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.BasicFunctionalityTest_BA49FD0FB46FD621-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@172c161a), locale=sq-XK, timezone=Pacific/Port_Moresby
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 13-ea (64-bit)/cpus=3,threads=1,free=160076568,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [TestLeaderElectionWithEmptyReplica, TestNumericTerms32, TestSlowCompositeReaderWrapper, V2ApiIntegrationTest, TestRequestStatusCollectionAPI, PeerSyncTest, DocValuesNotIndexedTest, TestQueryTypes, TestRandomFlRTGCloud, TestReplicationHandler, TestCoreDiscovery, XmlUpdateRequestHandlerTest, IgnoreLargeDocumentProcessorFactoryTest, TestRecoveryHdfs, SuggesterTest, MetricsConfigTest, TestShardHandlerFactory, TestRawTransformer, HttpPartitionTest, TestManagedSchemaThreadSafety, DistanceFunctionTest, TestLocalFSCloudBackupRestore, SystemInfoHandlerTest, DateFieldTest, SoftAutoCommitTest, TestSimGenericDistributedQueue, BadCopyFieldTest, BooleanFieldTest, TestCSVResponseWriter, TestSchemaVersionResource, CloudExitableDirectoryReaderTest, ClusterStateUpdateTest, TestStressCloudBlindAtomicUpdates, CustomTermsComponentTest, TestExportWriter, TestStandardQParsers, CollectionReloadTest, MultiSolrCloudTestCaseTest, TestSmileRequest, MBeansHandlerTest, SpatialHeatmapFacetsTest, TestConfigSets, SimplePostToolTest, SolrRrdBackendFactoryTest, AnalysisErrorHandlingTest, TestHighlightDedupGrouping, TestSimDistribStateManager, TestRestoreCore, BasicDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, TestNRTOpen, TestLuceneIndexBackCompat, MetricTriggerTest, ChaosMonkeySafeLeaderTest, AliasIntegrationTest, ZkControllerTest, AutoAddReplicasPlanActionTest, SmileWriterTest, TestDeleteCollectionOnDownNodes, LeaderElectionTest, TestCSVLoader, OverseerModifyCollectionTest, TestCloudJSONFacetSKG, SolrCLIZkUtilsTest, TestUninvertingReader, TestRandomDVFaceting, HdfsRecoverLeaseTest, TestCoreBackup, TestSolrQueryParser, DocumentBuilderTest, TestRuleBasedAuthorizationPlugin, TestAuthorizationFramework, ImplicitSnitchTest, TestInPlaceUpdatesDistrib, TestCopyFieldCollectionResource, ShardsWhitelistTest, TestAnalyzeInfixSuggestions, RulesTest, SolrCloudReportersTest, TestLockTree, HealthCheckHandlerTest, NodeLostTriggerTest, SSLMigrationTest, TestSolrCoreSnapshots, BigEndianAscendingWordDeserializerTest, TestJsonFacetsWithNestedObjects, SortSpecParsingTest, DistributedQueueTest, TestExclusionRuleCollectionAccess, DistributedFacetPivotLongTailTest, TestTolerantUpdateProcessorCloud, TestSearcherReuse, HdfsUnloadDistributedZkTest, DistributedFacetExistsSmallTest, TestSubQueryTransformerCrossCore, TestDelegationWithHadoopAuth, TestValueSourceCache, BufferStoreTest, BlockJoinFacetSimpleTest, TestInPlaceUpdatesStandalone, DirectoryFactoryTest, CdcrReplicationHandlerTest, TriggerIntegrationTest, DistribDocExpirationUpdateProcessorTest, TestManagedSynonymFilterFactory, CdcrVersionReplicationTest, TestDistributedMissingSort, ActionThrottleTest, DistributedSpellCheckComponentTest, DistributedTermsComponentTest, TestClassicSimilarityFactory, TermsComponentTest, HdfsThreadLeakTest, TestIntervalFaceting, TestSchemalessBufferedUpdates, BasicFunctionalityTest]
[junit4] Completed [272/844 (2!)] on J1 in 1.05s, 23 tests, 1 error <<< FAILURES!
[...truncated 44469 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/80/consoleText
[repro] Revision: 5a5a39ee18a2f40672384fd08ba18f395f0cfb5d
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] BasicFunctionalityTest
[repro] ActionThrottleTest
[repro] ant compile-test
[...truncated 3602 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.BasicFunctionalityTest|*.ActionThrottleTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=BA49FD0FB46FD621 -Dtests.slow=true -Dtests.locale=sq-XK -Dtests.timezone=Pacific/Port_Moresby -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 213 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.BasicFunctionalityTest
[repro] 0/5 failed: org.apache.solr.cloud.ActionThrottleTest
[repro] Exiting with code 0
[...truncated 78 lines...]