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...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org