You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/06/26 09:14:07 UTC
[JENKINS] Lucene-Solr-7.x-Windows (64bit/jdk-10.0.1) - Build # 655
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/655/
Java: 64bit/jdk-10.0.1 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.common.util.TestTimeSource.testEpochTime
Error Message:
SimTimeSource:50.0 time diff=24650000
Stack Trace:
java.lang.AssertionError: SimTimeSource:50.0 time diff=24650000
at __randomizedtesting.SeedInfo.seed([B8A346965880A3D6:80CF35B3CC500190]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.solr.common.util.TestTimeSource.testEpochTime
Error Message:
SimTimeSource:50.0 time diff=34300000
Stack Trace:
java.lang.AssertionError: SimTimeSource:50.0 time diff=34300000
at __randomizedtesting.SeedInfo.seed([B8A346965880A3D6:80CF35B3CC500190]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 16401 lines...]
[junit4] Suite: org.apache.solr.common.util.TestTimeSource
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_B8A346965880A3D6-001\init-core-data-001
[junit4] 2> 253947 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 253948 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 253948 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 253949 INFO (TEST-TestTimeSource.testEpochTime-seed#[B8A346965880A3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEpochTime
[junit4] 2> 263971 INFO (TEST-TestTimeSource.testEpochTime-seed#[B8A346965880A3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEpochTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestTimeSource -Dtests.method=testEpochTime -Dtests.seed=B8A346965880A3D6 -Dtests.slow=true -Dtests.locale=kea-CV -Dtests.timezone=ECT -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 10.0s J1 | TestTimeSource.testEpochTime <<<
[junit4] > Throwable #1: java.lang.AssertionError: SimTimeSource:50.0 time diff=34300000
[junit4] > at __randomizedtesting.SeedInfo.seed([B8A346965880A3D6:80CF35B3CC500190]:0)
[junit4] > at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
[junit4] > at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_B8A346965880A3D6-001
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=kea-CV, timezone=ECT
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 10.0.1 (64-bit)/cpus=3,threads=1,free=133569072,total=308596736
[junit4] 2> NOTE: All tests run in this JVM: [TestFastInputStream, CumulativeProbabilityEvaluatorTest, SolrDocumentTest, GraphTest, FloorEvaluatorTest, ConcurrentUpdateSolrClientBuilderTest, MultiplyEvaluatorTest, RecursiveEvaluatorTest, SchemaTest, FacetFieldTest, ConcatOperationTest, TestDocumentObjectBinder, NoOpResponseParserTest, TestCoreAdmin, SolrExampleStreamingTest, LengthEvaluatorTest, StreamDecoratorTest, AnlysisResponseBaseTest, LargeVolumeEmbeddedTest, SolrExampleBinaryTest, CeilingEvaluatorTest, TestCollectionStateWatchers, CloudSolrClientMultiConstructorTest, ReverseEvaluatorTest, LBHttpSolrClientBuilderTest, TestJsonRecordReader, LessThanEvaluatorTest, CommonAdminParamsTest, HttpClientUtilTest, TestLang, UuidEvaluatorTest, CosineEvaluatorTest, ExclusiveOrEvaluatorTest, JdbcTest, GreaterThanEvaluatorTest, TestCollectionAdminRequest, AppendEvaluatorTest, TestPolicy, JettyWebappTest, LargeVolumeJettyTest, SolrExampleEmbeddedTest, TestEmbeddedSolrServer, TestSolrProperties, CloudSolrClientBuilderTest, HttpSolrClientBuilderTest, HttpSolrClientConPoolTest, JDBCStreamTest, StreamExpressionTest, AbsoluteValueEvaluatorTest, AddEvaluatorTest, ArcCosineEvaluatorTest, ArcSineEvaluatorTest, ArcTangentEvaluatorTest, ArrayEvaluatorTest, CoalesceEvaluatorTest, DivideEvaluatorTest, EmpiricalDistributionEvaluatorTest, HyperbolicCosineEvaluatorTest, HyperbolicSineEvaluatorTest, NaturalLogEvaluatorTest, NormalDistributionEvaluatorTest, NormalizeEvaluatorTest, NotEvaluatorTest, OrEvaluatorTest, PowerEvaluatorTest, RoundEvaluatorTest, SineEvaluatorTest, SquareRootEvaluatorTest, TangentEvaluatorTest, TestUpdateRequestCodec, QueryResponseTest, TestClusteringResponse, TestDelegationTokenResponse, TestSpellCheckResponse, CommonParamsTest, ModifiableSolrParamsTest, ShardParamsTest, SolrParamTest, ContentStreamTest, JsonValidatorTest, TestHash, TestPathTrie, TestSolrJsonWriter, TestTimeSource]
[junit4] Completed [154/154 (1!)] on J1 in 10.04s, 1 test, 1 failure <<< FAILURES!
[...truncated 42890 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/655/consoleText
[repro] Revision: 99e5cf914028ec65381be0e980139f471bd8fb2d
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\solrj
[repro] TestTimeSource
[repro] ant compile-test
[...truncated 2492 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestTimeSource" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=B8A346965880A3D6 -Dtests.slow=true -Dtests.locale=kea-CV -Dtests.timezone=ECT -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 32 lines...]
[junit4] Suite: org.apache.solr.common.util.TestTimeSource
[junit4] 2> SLF4J: Class path contains multiple SLF4J bindings.
[junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/solrj/test-lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
[junit4] 2> SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory]
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_B8A346965880A3D6-001\init-core-data-001
[junit4] 2> 12272 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 12329 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.e.j.u.log Logging initialized @12360ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 12340 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 12424 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 12430 INFO (TEST-TestTimeSource.testEpochTime-seed#[B8A346965880A3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEpochTime
[junit4] 2> 22456 INFO (TEST-TestTimeSource.testEpochTime-seed#[B8A346965880A3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEpochTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestTimeSource -Dtests.method=testEpochTime -Dtests.seed=B8A346965880A3D6 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=kea-CV -Dtests.timezone=ECT -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 10.1s J1 | TestTimeSource.testEpochTime <<<
[junit4] > Throwable #1: java.lang.AssertionError: SimTimeSource:50.0 time diff=33369950
[junit4] > at __randomizedtesting.SeedInfo.seed([B8A346965880A3D6:80CF35B3CC500190]:0)
[junit4] > at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
[junit4] > at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_B8A346965880A3D6-001
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=kea-CV, timezone=ECT
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 10.0.1 (64-bit)/cpus=3,threads=1,free=63016720,total=97386496
[junit4] 2> NOTE: All tests run in this JVM: [TestTimeSource]
[junit4] Completed [1/5 (1!)] on J1 in 20.97s, 1 test, 1 failure <<< FAILURES!
[...truncated 1 lines...]
[junit4] Suite: org.apache.solr.common.util.TestTimeSource
[junit4] 2> SLF4J: Class path contains multiple SLF4J bindings.
[junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/solrj/test-lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
[junit4] 2> SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory]
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.util.TestTimeSource_B8A346965880A3D6-001\init-core-data-001
[junit4] 2> 12205 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 12239 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.e.j.u.log Logging initialized @12281ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 12248 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 12360 INFO (SUITE-TestTimeSource-seed#[B8A346965880A3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 12381 INFO (TEST-TestTimeSource.testEpochTime-seed#[B8A346965880A3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEpochTime
[junit4] 2> 22407 INFO (TEST-TestTimeSource.testEpochTime-seed#[B8A346965880A3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEpochTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestTimeSource -Dtests.method=testEpochTime -Dtests.seed=B8A346965880A3D6 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=kea-CV -Dtests.timezone=ECT -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 10.1s J0 | TestTimeSource.testEpochTime <<<
[junit4] > Throwable #1: java.lang.AssertionError: SimTimeSource:50.0 time diff=24650000
[junit4] > at __randomizedtesting.SeedInfo.seed([B8A346965880A3D6:80CF35B3CC500190]:0)
[junit4] > at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
[junit4] > at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.util.TestTimeSource_B8A346965880A3D6-001
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=kea-CV, timezone=ECT
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 10.0.1 (64-bit)/cpus=3,threads=1,free=63535344,total=97386496
[junit4] 2> NOTE: All tests run in this JVM: [TestTimeSource]
[junit4] Completed [2/5 (2!)] on J0 in 21.01s, 1 test, 1 failure <<< FAILURES!
[...truncated 24 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1568: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1092: There were test failures: 5 suites, 5 tests, 2 failures [seed: B8A346965880A3D6]
Total time: 45 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 2/5 failed: org.apache.solr.common.util.TestTimeSource
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=99e5cf914028ec65381be0e980139f471bd8fb2d, workspace=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows
[WARNINGS] Computing warning deltas based on reference build #654
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[JENKINS] Lucene-Solr-7.x-Windows (64bit/jdk-9.0.4) - Build # 657 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/657/
Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.common.util.TestTimeSource.testEpochTime
Error Message:
SimTimeSource:50.0 time diff=33560000
Stack Trace:
java.lang.AssertionError: SimTimeSource:50.0 time diff=33560000
at __randomizedtesting.SeedInfo.seed([F8DFB0FC781BBB77:C0B3C3D9ECCB1931]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.solr.common.util.TestTimeSource.testEpochTime
Error Message:
NanoTimeSource time diff=684300
Stack Trace:
java.lang.AssertionError: NanoTimeSource time diff=684300
at __randomizedtesting.SeedInfo.seed([F8DFB0FC781BBB77:C0B3C3D9ECCB1931]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:31)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 1841 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\temp\junit4-J0-20180627_065625_6844979939753899912161.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\temp\junit4-J1-20180627_065625_6853649490361982306368.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 310 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\test-framework\test\temp\junit4-J0-20180627_070131_48313847442786470670223.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\test-framework\test\temp\junit4-J1-20180627_070131_4837983620082743440812.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 1081 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\common\test\temp\junit4-J1-20180627_070221_4811249600723990024338.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\common\test\temp\junit4-J0-20180627_070221_4815352345776589458267.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 257 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\icu\test\temp\junit4-J1-20180627_070359_62213820479362820003748.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\icu\test\temp\junit4-J0-20180627_070359_62217313828744109232520.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 245 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J1-20180627_070410_17813178829536704871729.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 12 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J0-20180627_070410_1782411079728881984054.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 163 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J0-20180627_070438_97316865866624191354100.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J1-20180627_070438_9731947000838772246823.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 208 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\nori\test\temp\junit4-J1-20180627_070443_72210540190630694979087.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\nori\test\temp\junit4-J0-20180627_070443_72210181900165654032550.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 169 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\opennlp\test\temp\junit4-J0-20180627_070451_08912904586473267236287.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\opennlp\test\temp\junit4-J1-20180627_070451_08916621240677875688039.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 177 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J0-20180627_070454_7343604794009205706772.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J1-20180627_070454_73413979729741302430368.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 160 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J0-20180627_070503_1884220603644451947166.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J1-20180627_070503_188307648126639656533.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 168 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\stempel\test\temp\junit4-J0-20180627_070509_90412222360302174796456.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\stempel\test\temp\junit4-J1-20180627_070509_90411943071291071857335.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 176 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\uima\test\temp\junit4-J0-20180627_070513_64017835353072070887513.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 222 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\temp\junit4-J1-20180627_070531_64512596992983710827907.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 9 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\temp\junit4-J0-20180627_070531_6442354266613324136952.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1413 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\benchmark\test\temp\junit4-J1-20180627_070634_3292368155810169257208.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\benchmark\test\temp\junit4-J0-20180627_070634_32814838966984792556536.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 249 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\classification\test\temp\junit4-J0-20180627_070645_08015072343962449641843.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\classification\test\temp\junit4-J1-20180627_070645_08115761565962789600266.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 268 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\codecs\test\temp\junit4-J0-20180627_070703_4268377277106041662892.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\codecs\test\temp\junit4-J1-20180627_070703_42616761421051491474713.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 233 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\demo\test\temp\junit4-J0-20180627_070820_96410469781970796335488.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\demo\test\temp\junit4-J1-20180627_070820_96516123682422664371055.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 174 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\expressions\test\temp\junit4-J1-20180627_070825_020504178530123575567.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\expressions\test\temp\junit4-J0-20180627_070825_02011684693896185778441.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 233 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\facet\test\temp\junit4-J1-20180627_070830_5091431449346122249036.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\facet\test\temp\junit4-J0-20180627_070830_5093512765985693163373.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 183 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\grouping\test\temp\junit4-J0-20180627_070857_9412837645762220147143.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\grouping\test\temp\junit4-J1-20180627_070857_9414871903190894442155.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 259 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\highlighter\test\temp\junit4-J1-20180627_070904_3743727113747541814141.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\highlighter\test\temp\junit4-J0-20180627_070904_37315497180767568878989.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 164 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\join\test\temp\junit4-J0-20180627_070921_1523096802362503012642.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\join\test\temp\junit4-J1-20180627_070921_15210534020479344411865.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 154 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\memory\test\temp\junit4-J0-20180627_070931_6327234246543900866055.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\memory\test\temp\junit4-J1-20180627_070931_63312689352426661062524.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 194 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\temp\junit4-J0-20180627_070938_0094572629980350124298.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 5 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\misc\test\temp\junit4-J1-20180627_070938_0096317434021722170745.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 339 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\queries\test\temp\junit4-J1-20180627_070955_73815376899331696263290.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\queries\test\temp\junit4-J0-20180627_070955_7388616285168953507220.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 233 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\queryparser\test\temp\junit4-J0-20180627_071004_8028173074848314702556.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\queryparser\test\temp\junit4-J1-20180627_071004_8029259290262509666696.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 213 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\replicator\test\temp\junit4-J1-20180627_071012_99114570044857276640392.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\replicator\test\temp\junit4-J0-20180627_071012_9911424422171953203414.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 228 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\sandbox\test\temp\junit4-J0-20180627_071028_3319190921702957751323.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 7 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\sandbox\test\temp\junit4-J1-20180627_071028_3294893320978531320279.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 299 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\temp\junit4-J0-20180627_071058_1641012267144082283624.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\temp\junit4-J1-20180627_071058_1648914416861372557936.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 181 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial3d\test\temp\junit4-J0-20180627_071119_0205203983605745504582.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 17 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial3d\test\temp\junit4-J1-20180627_071119_02013553191342137984748.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 152 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial\test\temp\junit4-J0-20180627_071134_68716744972099134973180.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 256 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\suggest\test\temp\junit4-J0-20180627_071138_09316967823276320666077.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\suggest\test\temp\junit4-J1-20180627_071138_09310282376660456612662.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 4685 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\temp\junit4-J0-20180627_071250_6562523472381603353315.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 5 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\temp\junit4-J1-20180627_071250_6569176368555841960041.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 488 lines...]
[junit4] Suite: org.apache.solr.common.util.TestTimeSource
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.util.TestTimeSource_F8DFB0FC781BBB77-001\init-core-data-001
[junit4] 2> 35244 WARN (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=24 numCloses=24
[junit4] 2> 35244 INFO (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 35245 INFO (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-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> 35246 INFO (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 35266 INFO (TEST-TestTimeSource.testEpochTime-seed#[F8DFB0FC781BBB77]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEpochTime
[junit4] 2> 44798 INFO (TEST-TestTimeSource.testEpochTime-seed#[F8DFB0FC781BBB77]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEpochTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestTimeSource -Dtests.method=testEpochTime -Dtests.seed=F8DFB0FC781BBB77 -Dtests.slow=true -Dtests.locale=fr-CA -Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 9.56s J0 | TestTimeSource.testEpochTime <<<
[junit4] > Throwable #1: java.lang.AssertionError: NanoTimeSource time diff=684300
[junit4] > at __randomizedtesting.SeedInfo.seed([F8DFB0FC781BBB77:C0B3C3D9ECCB1931]:0)
[junit4] > at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
[junit4] > at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:31)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.util.TestTimeSource_F8DFB0FC781BBB77-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false): {}, locale=fr-CA, timezone=America/Indiana/Indianapolis
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=3,threads=1,free=21629648,total=105086976
[junit4] 2> NOTE: All tests run in this JVM: [NaturalLogEvaluatorTest, JdbcDriverTest, TestV2Request, TestTimeSource]
[junit4] Completed [11/154 (1!)] on J0 in 9.66s, 1 test, 1 failure <<< FAILURES!
[...truncated 453 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\temp\junit4-J1-20180627_080400_53010402216431825405530.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\temp\junit4-J0-20180627_080400_5301957396511425419523.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1608 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\temp\junit4-J0-20180627_080851_75416625457779736767619.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\temp\junit4-J1-20180627_080851_75414163029326268638962.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 792 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J1-20180627_080919_1695964240533315523232.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J0-20180627_080919_16917537110137991634398.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 563 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J0-20180627_081038_655838136043845736310.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J1-20180627_081038_6554780963569292088113.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 1279 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J0-20180627_081125_8991698824541387063763.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J1-20180627_081125_89916489835885695443508.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 656 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J1-20180627_081149_99915727430070249309162.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J0-20180627_081149_99913508249007135871026.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 568 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J1-20180627_081249_2796304100777886683103.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J0-20180627_081249_279468294306999139561.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 571 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J1-20180627_081321_7419037004134113490579.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J0-20180627_081321_7401479355615368063036.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 671 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J1-20180627_081350_96412004365356222418690.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J0-20180627_081350_96414850162764569054097.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 569 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-prometheus-exporter\test\temp\junit4-J0-20180627_081452_3225029720305323352214.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-prometheus-exporter\test\temp\junit4-J1-20180627_081452_3226510135392783620827.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 608 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-uima\test\temp\junit4-J0-20180627_081531_7656980250043670355.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-uima\test\temp\junit4-J1-20180627_081531_76511317568846001719607.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 558 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-velocity\test\temp\junit4-J0-20180627_081601_4614953430188501801551.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 34418 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/657/consoleText
[repro] Revision: 0741a9834ca1c7bef5e350c3f5d5181b445d61b9
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\solrj
[repro] TestTimeSource
[repro] ant compile-test
[...truncated 2492 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestTimeSource" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=F8DFB0FC781BBB77 -Dtests.slow=true -Dtests.locale=fr-CA -Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 32 lines...]
[junit4] Suite: org.apache.solr.common.util.TestTimeSource
[junit4] 2> SLF4J: Class path contains multiple SLF4J bindings.
[junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/solrj/test-lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
[junit4] 2> SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory]
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_F8DFB0FC781BBB77-001\init-core-data-001
[junit4] 2> 11477 INFO (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 11523 INFO (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-worker) [ ] o.e.j.u.log Logging initialized @11573ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 11532 INFO (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-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> 11538 INFO (SUITE-TestTimeSource-seed#[F8DFB0FC781BBB77]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 11545 INFO (TEST-TestTimeSource.testEpochTime-seed#[F8DFB0FC781BBB77]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEpochTime
[junit4] 2> 21569 INFO (TEST-TestTimeSource.testEpochTime-seed#[F8DFB0FC781BBB77]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEpochTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestTimeSource -Dtests.method=testEpochTime -Dtests.seed=F8DFB0FC781BBB77 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=fr-CA -Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 10.1s J1 | TestTimeSource.testEpochTime <<<
[junit4] > Throwable #1: java.lang.AssertionError: SimTimeSource:50.0 time diff=33560000
[junit4] > at __randomizedtesting.SeedInfo.seed([F8DFB0FC781BBB77:C0B3C3D9ECCB1931]:0)
[junit4] > at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:52)
[junit4] > at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_F8DFB0FC781BBB77-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false): {}, locale=fr-CA, timezone=America/Indiana/Indianapolis
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=3,threads=1,free=79872152,total=97320960
[junit4] 2> NOTE: All tests run in this JVM: [TestTimeSource]
[junit4] Completed [1/5 (1!)] on J1 in 20.45s, 1 test, 1 failure <<< FAILURES!
[...truncated 14 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\temp\junit4-J1-20180627_082129_02917647342204511618834.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 4 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\temp\junit4-J0-20180627_082129_03915580861432930413064.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 9 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1568: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1092: There were test failures: 5 suites, 5 tests, 1 failure [seed: F8DFB0FC781BBB77]
Total time: 44 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 1/5 failed: org.apache.solr.common.util.TestTimeSource
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=0741a9834ca1c7bef5e350c3f5d5181b445d61b9, workspace=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows
[WARNINGS] Computing warning deltas based on reference build #656
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[JENKINS] Lucene-Solr-7.x-Windows (64bit/jdk-10) - Build # 656 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/656/
Java: 64bit/jdk-10 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.search.TestRecovery.testExistOldBufferLog
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([980E37160FB7CE65:C65E2A4381785EEC]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertFalse(Assert.java:68)
at org.junit.Assert.assertFalse(Assert.java:79)
at org.apache.solr.search.TestRecovery.testExistOldBufferLog(TestRecovery.java:1071)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 15084 lines...]
[junit4] Suite: org.apache.solr.search.TestRecovery
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001
[junit4] 2> 2747771 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2747790 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2747802 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2747843 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2748138 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2748138 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2748149 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2748155 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2748155 INFO (SUITE-TestRecovery-seed#[980E37160FB7CE65]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2748158 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2748178 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2748191 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2748233 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2748242 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 2748242 INFO (coreLoadExecutor-16638-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@363d7013
[junit4] 2> 2748242 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2748242 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\]
[junit4] 2> 2748247 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=543637766, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2748286 WARN (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2748337 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 2748337 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2748339 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2748339 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2748340 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3732043509170748]
[junit4] 2> 2748345 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b75ff65[collection1] main]
[junit4] 2> 2748345 WARN (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 2748346 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2748346 INFO (searcherExecutor-16639-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b75ff65[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2748346 INFO (coreLoadExecutor-16638-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604363173308137472
[junit4] 2> 2748353 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1604363173313380352)]} 0 1
[junit4] 2> 2748353 ERROR (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\core.properties
[junit4] 2> 2748354 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2748374 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2748387 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2748431 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2748440 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 2748459 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ 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@363d7013
[junit4] 2> 2748459 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\]
[junit4] 2> 2748462 WARN (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2748513 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2748513 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2748524 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f47e58[collection1] main]
[junit4] 2> 2748525 WARN (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 2748525 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2748527 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f47e58[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1)))}
[junit4] 2> 2748635 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6763294677115692]
[junit4] 2> 2748637 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 2748638 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@81c119e[collection1] main]
[junit4] 2> 2748639 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2364d123
[junit4] 2> 2748639 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2364d123
[junit4] 2> 2748639 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38553429: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@732c37f3
[junit4] 2> 2748640 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@81c119e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1)))}
[junit4] 2> 2748648 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1604363173623758848)]} 0 0
[junit4] 2> 2748648 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604363173624807424,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2748648 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a267c02 commitCommandVersion:1604363173624807424
[junit4] 2> 2748681 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cd4c0ae[collection1] main]
[junit4] 2> 2748682 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2748682 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5cd4c0ae[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.5.0):C1)))}
[junit4] 2> 2748685 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 36
[junit4] 2> 2748687 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1604363173664653312)]} 0 1
[junit4] 2> 2748693 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@448c54fa[collection1] realtime]
[junit4] 2> 2748694 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=foo_t:hownowbrowncow (-1604363173665701888)} 0 7
[junit4] 2> 2748695 INFO (TEST-TestRecovery.testReload-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1604363173673041920)]} 0 0
[junit4] 2> 2748699 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2748699 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ee9118[collection1] realtime]
[junit4] 2> 2748701 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] 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 2
[junit4] 2> 2748701 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604363173680381952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2748701 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a267c02 commitCommandVersion:1604363173680381952
[junit4] 2> 2748708 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1cc7eeac[collection1] main]
[junit4] 2> 2748708 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2748709 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1cc7eeac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2748711 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 2748711 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2748712 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2748713 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C1]} 0 1
[junit4] 2> 2748713 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (1)]} 0 0
[junit4] 2> 2748713 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C3 (2)]} 0 0
[junit4] 2> 2748713 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000002.0 refcount=1}}
[junit4] 2> 2748713 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000002.0 refcount=1}}
[junit4] 2> 2748714 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C4 (3)]} 0 0
[junit4] 2> 2748714 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C5 (4)]} 0 0
[junit4] 2> 2748714 WARN (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\buffer.tlog.0001128362020712400 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 2749215 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2749215 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a267c02 commitCommandVersion:0
[junit4] 2> 2749256 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1857b05b[collection1] main]
[junit4] 2> 2749257 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2749258 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1857b05b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.5.0):C2)))}
[junit4] 2> 2749258 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C4 (3), C5 (4)]} 0 543
[junit4] 2> 2749258 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 2749259 WARN (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 2749260 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
[junit4] 2> 2749262 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C100 (5)]} 0 1
[junit4] 2> 2749262 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C101 (6)]} 0 0
[junit4] 2> 2749263 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000003.5 refcount=1}}
[junit4] 2> 2749263 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C103 (7)]} 0 0
[junit4] 2> 2749263 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C104 (8)]} 0 0
[junit4] 2> 2749263 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000003.5 refcount=1}}
[junit4] 2> 2749264 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000003.5 refcount=1}}
[junit4] 2> 2749264 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C105 (9)]} 0 0
[junit4] 2> 2749264 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C106 (10)]} 0 0
[junit4] 2> 2749264 WARN (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\buffer.tlog.0001128362571240300 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 2749765 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2749765 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a267c02 commitCommandVersion:0
[junit4] 2> 2749857 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4057d7df[collection1] main]
[junit4] 2> 2749859 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2749860 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4057d7df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.5.0):C2) Uninverting(_5(7.5.0):C4)))}
[junit4] 2> 2749860 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C105 (9), C106 (10)]} 0 595
[junit4] 2> 2749860 INFO (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 2749862 WARN (recoveryExecutor-16641-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 2749862 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id,_version_&sort=_version_+asc&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 2749864 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=6&wt=xml} status=0 QTime=1
[junit4] 2> 2749864 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2749865 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (13)]} 0 1
[junit4] 2> 2749866 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (14)]} 0 0
[junit4] 2> 2749866 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000004.13 refcount=1}}
[junit4] 2> 2749867 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (11)]} 0 0
[junit4] 2> 2749867 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (12)]} 0 0
[junit4] 2> 2749867 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604363174903021568,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2749867 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a267c02 commitCommandVersion:1604363174903021568
[junit4] 2> 2749991 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27f1ee77[collection1] main]
[junit4] 2> 2749993 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2749993 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27f1ee77[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.5.0):C2) Uninverting(_5(7.5.0):C4) Uninverting(_6(7.5.0):C2)))}
[junit4] 2> 2749993 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 125
[junit4] 2> 2749994 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
[junit4] 2> 2749994 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id,_version_&sort=_version_+desc&rows=2&wt=xml} hits=8 status=0 QTime=0
[junit4] 2> 2749996 INFO (TEST-TestRecovery.testDropBuffered-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (12)]} 0 1
[junit4] 2> 2749999 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2750000 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13eff87b[collection1] realtime]
[junit4] 2> 2750000 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] 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> 2750000 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604363175042482176,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2750000 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a267c02 commitCommandVersion:1604363175042482176
[junit4] 2> 2750010 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3843b777[collection1] main]
[junit4] 2> 2750012 INFO (searcherExecutor-16644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3843b777[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2750012 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2750026 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 25
[junit4] 2> 2750028 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_1 (15)]} 0 1
[junit4] 2> 2750028 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_2 (18)]} 0 0
[junit4] 2> 2750041 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e02e6d9[collection1] realtime]
[junit4] 2> 2750042 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@71b3930f[collection1] realtime]
[junit4] 2> 2750043 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-17&wt=json&indent=true}{deleteByQuery=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4 (-17)} 0 15
[junit4] 2> 2750043 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{_version_=16,id=RDBQ2_3} DBQs=[DBQ{version=17,q=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4}]
[junit4] 2> 2750052 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65bab522[collection1] realtime]
[junit4] 2> 2750052 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_3 (16)]} 0 9
[junit4] 2> 2750053 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_4 (19)]} 0 0
[junit4] 2> 2750053 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 2750054 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1989342533
[junit4] 2> 2750054 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2750054 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@562af59: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@758b23ac
[junit4] 2> 2750058 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2750058 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3f16eaed: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@50663eba
[junit4] 2> 2750060 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2750060 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24ac34e1: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@27a580bc
[junit4] 2> 2750061 INFO (coreCloseExecutor-16648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@14c3f541
[junit4] 2> 2750061 INFO (coreCloseExecutor-16648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=14c3f541
[junit4] 2> 2750061 INFO (coreCloseExecutor-16648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@de40f1a: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@732c37f3
[junit4] 2> 2750085 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2750104 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2750112 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2750163 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2750511 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2750511 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2750523 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2750530 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2750530 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2750532 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2750550 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2750560 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2750613 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2750621 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 2750622 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ 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@363d7013
[junit4] 2> 2750622 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2750622 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\]
[junit4] 2> 2750625 WARN (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2750673 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 2750673 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2750679 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2750679 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2750679 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=543637766, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2750682 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1489e937[collection1] main]
[junit4] 2> 2750682 WARN (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 2750683 INFO (coreLoadExecutor-16658-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2750683 INFO (searcherExecutor-16659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1489e937[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2750685 WARN (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000006.15 refcount=1} active=false starting pos=0 inSortedOrder=false
[junit4] 2> 2750686 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 2751195 INFO (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7acf8cac[collection1] realtime]
[junit4] 2> 2751196 INFO (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@68cf44ff[collection1] realtime]
[junit4] 2> 2751197 INFO (replayUpdatesExecutor-16652-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=16,id=RDBQ2_3} DBQs=[DBQ{version=17,q=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4}]
[junit4] 2> 2751204 INFO (replayUpdatesExecutor-16652-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15340530[collection1] realtime]
[junit4] 2> 2751697 INFO (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2751697 INFO (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7b08bb21 commitCommandVersion:0
[junit4] 2> 2751914 INFO (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@670d79c0[collection1] main]
[junit4] 2> 2751914 INFO (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2751915 INFO (searcherExecutor-16659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@670d79c0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(7.5.0):C6/3:delGen=1) Uninverting(_b(7.5.0):C3)))}
[junit4] 2> 2751916 INFO (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[RDBQ2_1 (15), RDBQ2_2 (18), RDBQ2_3 (16), RDBQ2_4 (19)],deleteByQuery=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4 (-17)} 0 1229
[junit4] 2> 2751916 WARN (recoveryExecutor-16661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4] 2> 2751916 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 2751920 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2751926 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@21c9c2bd[collection1] realtime]
[junit4] 2> 2751927 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] 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 7
[junit4] 2> 2751927 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604363177063088128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2751927 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7b08bb21 commitCommandVersion:1604363177063088128
[junit4] 2> 2751934 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@469e4f15[collection1] main]
[junit4] 2> 2751934 INFO (searcherExecutor-16659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@469e4f15[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2751934 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2751938 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 11
[junit4] 2> 2751944 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ1_1 (20)]} 0 5
[junit4] 2> 2751949 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3229cb1e[collection1] realtime]
[junit4] 2> 2751950 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-22&wt=json&indent=true}{deleteByQuery=id:RDBQ1_2 (-22)} 0 5
[junit4] 2> 2751950 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{_version_=21,id=RDBQ1_2} DBQs=[DBQ{version=22,q=id:RDBQ1_2}]
[junit4] 2> 2751956 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3267f4d8[collection1] realtime]
[junit4] 2> 2751957 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ1_2 (21)]} 0 6
[junit4] 2> 2751958 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ1_3 (23)]} 0 0
[junit4] 2> 2751958 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 2751958 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=727963080
[junit4] 2> 2751958 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2751958 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2cbcf65a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6bdab840
[junit4] 2> 2751962 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2751962 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@616607d0: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@50663eba
[junit4] 2> 2751964 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2751964 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e0a42fc: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@27a580bc
[junit4] 2> 2751965 INFO (coreCloseExecutor-16664-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f634242
[junit4] 2> 2751965 INFO (coreCloseExecutor-16664-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=f634242
[junit4] 2> 2751965 INFO (coreCloseExecutor-16664-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@395453bc: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@166043ff
[junit4] 2> 2751978 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2751995 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2752004 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2752052 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2752269 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2752269 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2752284 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2752290 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2752290 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2752293 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2752314 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2752323 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2752375 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2752384 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 2752385 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ 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@363d7013
[junit4] 2> 2752385 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2752385 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\]
[junit4] 2> 2752404 WARN (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2752501 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 2752502 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2752507 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2752507 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2752510 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=543637766, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2752512 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@35af3143[collection1] main]
[junit4] 2> 2752512 WARN (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 2752512 INFO (coreLoadExecutor-16674-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2752514 INFO (searcherExecutor-16675-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@35af3143[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2752516 WARN (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000009.20 refcount=1} active=false starting pos=0 inSortedOrder=false
[junit4] 2> 2752517 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 2753049 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5299b6a4[collection1] realtime]
[junit4] 2> 2753050 INFO (replayUpdatesExecutor-16668-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=21,id=RDBQ1_2} DBQs=[DBQ{version=22,q=id:RDBQ1_2}]
[junit4] 2> 2753056 INFO (replayUpdatesExecutor-16668-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f1d7ef[collection1] realtime]
[junit4] 2> 2753657 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2753657 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f816b79 commitCommandVersion:0
[junit4] 2> 2754563 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@348fd492[collection1] main]
[junit4] 2> 2754564 INFO (searcherExecutor-16675-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@348fd492[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_d(7.5.0):C1) Uninverting(_f(7.5.0):C1)))}
[junit4] 2> 2754564 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2754564 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[RDBQ1_1 (20), RDBQ1_2 (21), RDBQ1_3 (23)],deleteByQuery=id:RDBQ1_2 (-22)} 0 2048
[junit4] 2> 2754564 WARN (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4] 2> 2754565 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 2754568 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2754568 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@58b03735[collection1] realtime]
[junit4] 2> 2754568 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] 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> 2754568 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604363179832377344,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2754568 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f816b79 commitCommandVersion:1604363179832377344
[junit4] 2> 2754576 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e976cd7[collection1] main]
[junit4] 2> 2754577 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2754578 INFO (searcherExecutor-16675-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e976cd7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2754581 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 12
[junit4] 2> 2754581 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2754582 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c1 (24)]} 0 1
[junit4] 2> 2754583 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c2 (25)]} 0 0
[junit4] 2> 2754583 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c3 (26)]} 0 0
[junit4] 2> 2754583 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000012.24 refcount=1}}
[junit4] 2> 2754584 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c4 (27)]} 0 0
[junit4] 2> 2754584 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c5 (28)]} 0 0
[junit4] 2> 2754584 WARN (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\buffer.tlog.0001128367890883300 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 2755109 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2755109 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f816b79 commitCommandVersion:0
[junit4] 2> 2755173 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@749c9466[collection1] main]
[junit4] 2> 2755173 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2755175 INFO (searcherExecutor-16675-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@749c9466[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_g(7.5.0):C2)))}
[junit4] 2> 2755175 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[c4 (27), c5 (28)]} 0 590
[junit4] 2> 2755175 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 2755177 WARN (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 2755179 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=1
[junit4] 2> 2755181 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c100 (29)]} 0 1
[junit4] 2> 2755181 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c101 (30)]} 0 0
[junit4] 2> 2755182 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000013.29 refcount=1}}
[junit4] 2> 2755182 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c103 (31)]} 0 0
[junit4] 2> 2755182 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c104 (32)]} 0 0
[junit4] 2> 2755182 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\tlog.0000000000000000013.29 refcount=1}}
[junit4] 2> 2755183 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c105 (33)]} 0 0
[junit4] 2> 2755183 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c106 (34)]} 0 0
[junit4] 2> 2755183 WARN (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestRecovery_980E37160FB7CE65-001\init-core-data-001\tlog\buffer.tlog.0001128368490189900 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 2755704 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2755704 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f816b79 commitCommandVersion:0
[junit4] 2> 2756676 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7db7635a[collection1] main]
[junit4] 2> 2756678 INFO (searcherExecutor-16675-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7db7635a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_g(7.5.0):C2) Uninverting(_h(7.5.0):C4)))}
[junit4] 2> 2756678 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2756678 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[c105 (33), c106 (34)]} 0 1494
[junit4] 2> 2756678 INFO (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 2756680 WARN (recoveryExecutor-16677-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 2756680 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id,_version_&sort=_version_+asc&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 2756682 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=6&wt=xml} status=0 QTime=1
[junit4] 2> 2756685 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2756685 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1673e3f8[collection1] realtime]
[junit4] 2> 2756686 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] 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> 2756686 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604363182053261312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2756686 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f816b79 commitCommandVersion:1604363182053261312
[junit4] 2> 2756696 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@729611a4[collection1] main]
[junit4] 2> 2756697 INFO (searcherExecutor-16675-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@729611a4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2756697 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2756805 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 119
[junit4] 2> 2756805 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=459988660
[junit4] 2> 2756806 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2756806 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c2e4897: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3d8cd4df
[junit4] 2> 2756810 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2756810 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e6daa20: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@50663eba
[junit4] 2> 2756813 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2756813 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6432fc98: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@27a580bc
[junit4] 2> 2756814 INFO (coreCloseExecutor-16680-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@408f953f
[junit4] 2> 2756814 INFO (coreCloseExecutor-16680-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=408f953f
[junit4] 2> 2756814 INFO (coreCloseExecutor-16680-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@27ab3a56: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1861d930
[junit4] 2> 2756825 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2756843 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2756851 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2756898 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2756961 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2756961 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2756973 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2756978 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2756980 INFO (TEST-TestRecovery.testRemoveOldLogs-seed#[980E37160FB7CE65]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@363d7013
[junit4] 2> 2756984 INFO (coreLoadExecutor-16690-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/c
[...truncated too long message...]
dexFingerprintCachingTest, DataDrivenBlockJoinTest, TestReplicationHandlerBackup, HttpTriggerListenerTest, TestRebalanceLeaders, TestSlowCompositeReaderWrapper, SignatureUpdateProcessorFactoryTest, OpenCloseCoreStressTest, TestEmbeddedSolrServerConstructors, TestComponentsName, HdfsLockFactoryTest, WrapperMergePolicyFactoryTest, TimeRoutedAliasUpdateProcessorTest, TestSortableTextField, TestSweetSpotSimilarityFactory, TestJettySolrRunner, TestMultiValuedNumericRangeQuery, TestShardHandlerFactory, SolrCloudReportersTest, TestQuerySenderNoQuery, TestFieldCacheReopen, BlobRepositoryCloudTest, TestAtomicUpdateErrorCases, CollectionStateFormat2Test, DistributedFacetPivotWhiteBoxTest, MissingSegmentRecoveryTest, TestBM25SimilarityFactory, TestManagedSynonymFilterFactory, TestDynamicFieldResource, TestImplicitCoreProperties, TestClusterProperties, QueryResultKeyTest, SuggestComponentTest, HdfsChaosMonkeySafeLeaderTest, DocumentAnalysisRequestHandlerTest, TestGroupingSearch, OverseerTest, FullHLLTest, TestMissingGroups, TestQueryUtils, MetricsHistoryIntegrationTest, TestBadConfig, TestCustomStream, TestTolerantUpdateProcessorCloud, ExternalFileFieldSortTest, TestPerFieldSimilarity, SharedFSAutoReplicaFailoverTest, TestConfig, StatelessScriptUpdateProcessorFactoryTest, TestNestedDocsSort, TestNamedUpdateProcessors, DistribDocExpirationUpdateProcessorTest, CoreAdminHandlerTest, BasicAuthStandaloneTest, CollectionTooManyReplicasTest, MultiSolrCloudTestCaseTest, SegmentsInfoRequestHandlerTest, UtilsToolTest, BinaryUpdateRequestHandlerTest, TestStandardQParsers, TestFieldSortValues, SpatialRPTFieldTypeTest, TestCoreDiscovery, NumericFieldsTest, CdcrReplicationDistributedZkTest, TestUtilizeNode, TestStressRecovery, TestGraphMLResponseWriter, TestExtendedDismaxParser, URLClassifyProcessorTest, TestLFUCache, ZkCLITest, TestReloadDeadlock, ShardSplitTest, TestCloudManagedSchema, TestBlobHandler, DeleteStatusTest, NodeMutatorTest, TestFiltering, TestDefaultStatsCache, TestAuthenticationFramework, HighlighterMaxOffsetTest, TestLuceneMatchVersion, SpellCheckCollatorTest, ParsingFieldUpdateProcessorsTest, TestDynamicLoading, ChaosMonkeySafeLeaderTest, TestXmlQParserPlugin, TestCrossCoreJoin, SSLMigrationTest, TestTrie, NodeMarkersRegistrationTest, TestDistributedSearch, LukeRequestHandlerTest, SpatialHeatmapFacetsTest, AutoAddReplicasIntegrationTest, TestFastOutputStream, ReplaceNodeTest, CircularListTest, ShufflingReplicaListTransformerTest, TestFieldResource, QueryEqualityTest, TestSolrCloudWithKerberosAlt, TermVectorComponentTest, TestLRUStatsCache, TestTrackingShardHandlerFactory, DocValuesNotIndexedTest, TestCSVResponseWriter, PingRequestHandlerTest, DisMaxRequestHandlerTest, TestPHPSerializedResponseWriter, HdfsRestartWhileUpdatingTest, OrderedExecutorTest, TestFieldCacheWithThreads, SolrMetricManagerTest, BigEndianAscendingWordDeserializerTest, TestCollectionAPI, TestRetrieveFieldsOptimizer, HdfsRecoverLeaseTest, InfoHandlerTest, AnalysisAfterCoreReloadTest, TestConfigReload, PeerSyncTest, TestTriggerIntegration, ClassificationUpdateProcessorIntegrationTest, BooleanFieldTest, TestApiFramework, TestClusterStateProvider, BadCopyFieldTest, MetricsHandlerTest, HdfsUnloadDistributedZkTest, OverseerModifyCollectionTest, TestWordDelimiterFilterFactory, PropertiesRequestHandlerTest, BlockJoinFacetRandomTest, CdcrBidirectionalTest, AutoscalingHistoryHandlerTest, IndexSchemaTest, TestJoin, TransactionLogTest, TestNumericTerms32, JsonLoaderTest, TestSolrCloudWithHadoopAuthPlugin, UpdateParamsTest, AutoScalingHandlerTest, TestAddFieldRealTimeGet, TestRestoreCore, TestUninvertingReader, TestInitParams, XmlUpdateRequestHandlerTest, BlockCacheTest, SuggesterFSTTest, TestLegacyField, TestSmileRequest, TestCloudDeleteByQuery, TestUniqueKeyFieldResource, TestSolrDeletionPolicy2, EmbeddedSolrNoSerializeTest, TestNodeAddedTrigger, TestIBSimilarityFactory, FileBasedSpellCheckerTest, TestRecoveryHdfs, BJQParserTest, SolrRrdBackendFactoryTest, TestScoreJoinQPScore, DeleteLastCustomShardedReplicaTest, TestLazyCores, TestPayloadCheckQParserPlugin, TestExactSharedStatsCache, ExecutePlanActionTest, TestRandomFlRTGCloud, TestStreamBody, DeleteReplicaTest, TestSurroundQueryParser, UnloadDistributedZkTest, TestSQLHandlerNonCloud, HttpSolrCallGetCoreTest, TestRawResponseWriter, TestConfigSetImmutable, TestLegacyNumericRangeQueryBuilder, TestRuleBasedAuthorizationPlugin, TestIndexSearcher, TestDFISimilarityFactory, ResponseHeaderTest, AssignTest, TestShortCircuitedRequests, LeaderVoteWaitTimeoutTest, TestSchemaVersionResource, HLLUtilTest, BasicFunctionalityTest, ConvertedLegacyTest, CursorPagingTest, EchoParamsTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestHighlightDedupGrouping, TestRandomFaceting, TestSimpleTrackingShardHandler, TestSolrCoreProperties, TestTolerantSearch, PathHierarchyTokenizerFactoryTest, TestLuceneIndexBackCompat, AsyncCallRequestStatusResponseTest, BasicDistributedZkTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ClusterStateUpdateTest, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteNodeTest, DeleteShardTest, DistributedQueueTest, FullSolrCloudDistribCmdsTest, LeaderElectionContextKeyTest, LeaderElectionIntegrationTest, LeaderFailoverAfterPartitionTest, LeaderFailureAfterFreshStartTest, LegacyCloudClusterPropTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, PeerSyncReplicationTest, RecoveryAfterSoftCommitTest, TestCloudConsistency, TestCloudJSONFacetJoinDomain, TestCloudJSONFacetSKG, TestCloudPseudoReturnFields, TestCloudRecovery, TestConfigSetsAPIExclusivity, TestCryptoKeys, TestDistribDocBasedVersion, TestLeaderElectionZkExpiry, TestLockTree, TestMiniSolrCloudClusterSSL, TestPullReplica, TestSSLRandomization, TestSolrCloudWithDelegationTokens, TestStressCloudBlindAtomicUpdates, TestStressInPlaceUpdates, TestStressLiveNodes, TestZkChroot, TlogReplayBufferedWhileIndexingTest, ZkControllerTest, ZkFailoverTest, ZkShardTermsTest, CollectionReloadTest, CollectionsAPIAsyncDistributedZkTest, CustomCollectionTest, HdfsCollectionsAPIDistributedZkTest, TestHdfsCloudBackupRestore, TestLocalFSCloudBackupRestore, TestReplicaProperties, AutoAddReplicasPlanActionTest, MetricTriggerIntegrationTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, RestoreTriggerStateTest, TestPolicyCloud, TestComputePlanAction, TestDistribStateManager, TestExecutePlanAction, TestNodeLostTrigger, TestPolicyCloud, TestSimDistributedQueue, BaseCdcrDistributedZkTest, CdcrBootstrapTest, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsRecoveryZkTest, HdfsThreadLeakTest, HdfsWriteToMultipleCollectionsTest, ZkStateReaderTest, AlternateDirectoryTest, CachingDirectoryFactoryTest, CoreSorterTest, ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, RAMDirectoryFactoryTest, RequestHandlersTest, ResourceLoaderTest, SolrCoreCheckLockOnStartupTest, TestBackupRepositoryFactory, TestConfigOverlay, TestConfigSetProperties, TestConfigSets, TestDirectoryFactory, TestDynamicURP, TestInfoStreamLogging, TestNRTOpen, TestSimpleTextCodec, TestSolrConfigHandler, ResponseBuilderTest, SearchHandlerTest, TestCSVLoader, TestCoreBackup, TestHdfsBackupRestoreCore, TestReqParamsAPI, TestSolrConfigHandlerConcurrent, TestSystemCollAutoCreate, CoreMergeIndexesAdminHandlerTest, LoggingHandlerTest, StatsReloadRaceTest, DebugComponentTest, DistributedFacetExistsSmallTest, DistributedFacetPivotLongTailTest, DistributedMLTComponentTest, DistributedQueryComponentOptimizationTest, DistributedSpellCheckComponentTest, FacetPivotSmallTest, InfixSuggestersTest, QueryElevationComponentTest, StatsComponentTest, TestExpandComponent, TestHttpShardHandlerFactory, Tagger2Test, TaggingAttributeTest, XmlInterpolationTest, FastVectorHighlighterTest, HighlighterConfigTest, HighlighterTest, TestPostingsSolrHighlighter, UninvertDocValuesMergePolicyTest, TestNumericRangeQuery32, TestNumericTokenStream, MetricsConfigTest, SolrMetricReporterTest, SolrMetricsIntegrationTest, SolrJmxReporterTest, SimpleFacetsTest, TestIntervalFaceting, TestExportWriter, TestSolrQueryResponse, TestSubQueryTransformerCrossCore, TestManagedResource, TestRestManager, TestFieldTypeCollectionResource, TestFieldTypeResource, TestSchemaResource, TestSerializedLuceneMatchVersion, TestManagedSynonymGraphFilterFactory, CopyFieldTest, CurrencyFieldTypeTest, DateFieldTest, DateRangeFieldTest, DocValuesMissingTest, IndexSchemaRuntimeFieldTest, ManagedSchemaRoundRobinCloudTest, PrimitiveFieldTypeTest, RequiredFieldsTest, SynonymTokenizerTest, TestBinaryField, TestBulkSchemaConcurrent, TestCollationField, TestHalfAndHalfDocValues, TestManagedSchema, TestManagedSchemaAPI, TestSchemaManager, TestSchemalessBufferedUpdates, TestUseDocValuesAsStored2, AnalyticsMergeStrategyTest, TestReRankQParserPlugin, TestRealTimeGet, TestRecovery]
[junit4] Completed [729/819 (1!)] on J0 in 52.80s, 20 tests, 1 failure <<< FAILURES!
[...truncated 44202 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/656/consoleText
[repro] Revision: 17d253262cfc1ca24a13618aa1811b21342be267
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestRecovery
[repro] ant compile-test
[...truncated 3344 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestRecovery" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=980E37160FB7CE65 -Dtests.slow=true -Dtests.locale=es-MX -Dtests.timezone=Africa/Bangui -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 169 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.search.TestRecovery
[repro] Exiting with code 0
[...truncated 75 lines...]