Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2283/
3 tests failed. FAILED: org.apache.solr.search.TestRecovery.testBuffering Error Message: expected:<1> but was:<3> Stack Trace: java.lang.AssertionError: expected:<1> but was:<3> at __randomizedtesting.SeedInfo.seed([7919C757183A1F7D:64F7697CB963BE56]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.search.TestRecovery.testBuffering(TestRecovery.java:494) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.search.TestRecovery.testLogReplay Error Message: expected:<7> but was:<8> Stack Trace: java.lang.AssertionError: expected:<7> but was:<8> at __randomizedtesting.SeedInfo.seed([7919C757183A1F7D:E778ED75859101F1]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.search.TestRecovery.testLogReplay(TestRecovery.java:175) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.api.collections.TestHdfsCloudBackupRestore.test Error Message: Could not load collection from ZK: hdfsbackuprestore_restored Stack Trace: org.apache.solr.common.SolrException: Could not load collection from ZK: hdfsbackuprestore_restored at __randomizedtesting.SeedInfo.seed([7919C757183A1F7D:F14DF88DB6C67285]:0) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1108) at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:647) at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:137) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154) at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.testBackupAndRestore(AbstractCloudBackupRestoreTestCase.java:291) at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.test(AbstractCloudBackupRestoreTestCase.java:142) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/hdfsbackuprestore_restored/state.json at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215) at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339) at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1120) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1106) ... 44 more Build Log: [...truncated 11914 lines...] [junit4] Suite: org.apache.solr.search.TestRecovery [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001 [junit4] 2> 553233 WARN (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 553233 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 553266 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 553266 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 553267 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 553267 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 553394 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 553473 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 553921 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 554259 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 554294 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 554294 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 554344 INFO (coreLoadExecutor-801-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 554345 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 554588 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 554649 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 554993 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 555011 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 555011 INFO (coreLoadExecutor-801-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@62f3b1e0 [junit4] 2> 555012 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 555012 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/] [junit4] 2> 555031 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1599790333, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 555054 WARN (coreLoadExecutor-801-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> 555211 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 555211 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 555212 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 555212 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 555213 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=46.9033203125, floorSegmentMB=0.53515625, forceMergeDeletesPctAllowed=2.1284675670837827, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48064500308302227 [junit4] 2> 555229 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@739980df[collection1] main] [junit4] 2> 555229 WARN (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 555229 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 555266 INFO (searcherExecutor-802-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@739980df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 555266 INFO (coreLoadExecutor-801-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1590947497487892480 [junit4] 2> 555272 INFO (SUITE-TestRecovery-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 555281 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLogReplayWithReorderedDBQByAsterixAndChildDocs [junit4] 2> 555282 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 555283 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@57079047[collection1] realtime] [junit4] 2> 555283 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] 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> 555284 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1590947497506766848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 555284 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@41b7155c commitCommandVersion:1590947497506766848 [junit4] 2> 555426 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e08864[collection1] main] [junit4] 2> 555427 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 555428 INFO (searcherExecutor-802-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e08864[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 555428 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 144 [junit4] 2> 555473 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_1]} 0 15 [junit4] 2> 555474 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_2 (3)]} 0 0 [junit4] 2> 555573 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e5b2d57[collection1] realtime] [junit4] 2> 555575 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@145ca4b5[collection1] realtime] [junit4] 2> 555608 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-2&wt=json&indent=true}{deleteByQuery=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4 (-2)} 0 134 [junit4] 2> 555609 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{_version_=1,id=RDBQ2_3} DBQs=[DBQ{version=2,q=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4}] [junit4] 2> 555681 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@61b7a968[collection1] realtime] [junit4] 2> 555681 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_3 (1)]} 0 72 [junit4] 2> 555682 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_4 (4)]} 0 0 [junit4] 2> 555717 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=35 [junit4] 2> 555718 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=69935993 [junit4] 2> 555718 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 555718 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5dfca840: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2eb8bb82 [junit4] 2> 555721 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 555721 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@72210948: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b934925 [junit4] 2> 555777 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 555777 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@54dd8608: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@40c7bb53 [junit4] 2> 555835 INFO (coreCloseExecutor-807-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@407f92f6 [junit4] 2> 555835 INFO (coreCloseExecutor-807-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1082102518 [junit4] 2> 555835 INFO (coreCloseExecutor-807-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@74ce0663: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1eb3b0b4 [junit4] 2> 556157 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 556317 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 556521 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 556990 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 557335 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 557372 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 557390 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 557427 INFO (coreLoadExecutor-814-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 557428 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 557679 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 557738 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 558137 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 558159 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 558159 INFO (coreLoadExecutor-814-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@62f3b1e0 [junit4] 2> 558172 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 558172 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/] [junit4] 2> 558189 WARN (coreLoadExecutor-814-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> 558586 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 558586 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 558587 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 558587 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 558678 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1599790333, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 558679 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e861e3f[collection1] main] [junit4] 2> 558679 WARN (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 558680 INFO (coreLoadExecutor-814-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 558681 INFO (searcherExecutor-815-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e861e3f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 558731 WARN (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000001 refcount=2} active=false starting pos=0 inSortedOrder=false [junit4] 2> 558751 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=19 [junit4] 2> 558808 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6069a62a[collection1] realtime] [junit4] 2> 558865 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@9fcca46[collection1] realtime] [junit4] 2> 558866 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=1,id=RDBQ2_3} DBQs=[DBQ{version=2,q=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4}] [junit4] 2> 558899 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d137dd7[collection1] realtime] [junit4] 2> 558900 INFO (recoveryExecutor-817-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> 558900 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@551d9de7 commitCommandVersion:0 [junit4] 2> 559320 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e481262[collection1] main] [junit4] 2> 559320 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 559322 INFO (searcherExecutor-815-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e481262[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c6/3:delGen=1) Uninverting(_3(8.0.0):c3)))} [junit4] 2> 559322 INFO (recoveryExecutor-817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[RDBQ2_1, RDBQ2_2 (3), RDBQ2_3 (1), RDBQ2_4 (4)],deleteByQuery=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4 (-2)} 0 590 [junit4] 2> 559322 WARN (recoveryExecutor-817-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> 559334 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=6 status=0 QTime=0 [junit4] 2> 559335 INFO (TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[7919C757183A1F7D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLogReplayWithReorderedDBQByAsterixAndChildDocs [junit4] 2> 559337 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNewDBQAndDocMatchingOldDBQDuringLogReplay [junit4] 2> 559371 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 559372 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@195e796b[collection1] realtime] [junit4] 2> 559372 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] 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> 559372 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1590947501793345536,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 559372 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@551d9de7 commitCommandVersion:1590947501793345536 [junit4] 2> 559464 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8b985a2[collection1] main] [junit4] 2> 559465 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 559466 INFO (searcherExecutor-815-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8b985a2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 559467 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 94 [junit4] 2> 559501 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[B0 (1590947501892960256)]} 0 34 [junit4] 2> 559502 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[B1 (1590947501928611840)]} 0 0 [junit4] 2> 559502 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[B2 (1590947501929660416)]} 0 0 [junit4] 2> 559555 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4570812e[collection1] realtime] [junit4] 2> 559557 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@57c5dcd[collection1] realtime] [junit4] 2> 559642 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{deleteByQuery=id:B1 OR id:B3 OR id:B6 (-1590947501929660417)} 0 140 [junit4] 2> 559644 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[B3 (1590947502077509632)]} 0 1 [junit4] 2> 559644 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[B4 (1590947502078558208)]} 0 0 [junit4] 2> 559644 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[B5 (1590947502078558209)]} 0 0 [junit4] 2> 559665 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=20 [junit4] 2> 559665 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1567457248 [junit4] 2> 559665 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 559665 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@63355653: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@52b4d02c [junit4] 2> 559752 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 559752 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1eabfdd9: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b934925 [junit4] 2> 559755 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 559755 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@79f9ca92: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@40c7bb53 [junit4] 2> 559836 INFO (coreCloseExecutor-820-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5df1f6d [junit4] 2> 559837 INFO (coreCloseExecutor-820-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=98508653 [junit4] 2> 559837 INFO (coreCloseExecutor-820-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e0f1439: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5dc5690a [junit4] 2> 559898 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 560080 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 560156 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 560718 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 561007 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 561030 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 561030 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 561052 INFO (coreLoadExecutor-827-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 561052 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 561185 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 561235 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 561622 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 561654 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 561654 INFO (coreLoadExecutor-827-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@62f3b1e0 [junit4] 2> 561654 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 561654 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/] [junit4] 2> 561668 WARN (coreLoadExecutor-827-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> 561800 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 561800 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 561854 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 561854 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 561855 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1599790333, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 561856 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1699ecea[collection1] main] [junit4] 2> 561870 WARN (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 561870 INFO (coreLoadExecutor-827-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 561871 INFO (searcherExecutor-828-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1699ecea[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 561959 WARN (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000004 refcount=2} active=false starting pos=0 inSortedOrder=false [junit4] 2> 561963 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=3 [junit4] 2> 561965 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{deleteByQuery=id:B2 OR id:B4 (-1590947504511254528)} 0 1 [junit4] 2> 561965 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=1590947501892960256,id=B0} DBQs=[DBQ{version=1590947504511254528,q=id:B2 OR id:B4}] [junit4] 2> 562000 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b8eb218[collection1] realtime] [junit4] 2> 562000 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=1590947501928611840,id=B1} DBQs=[DBQ{version=1590947504511254528,q=id:B2 OR id:B4}] [junit4] 2> 562052 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a9abdf6[collection1] realtime] [junit4] 2> 562089 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=1590947501929660416,id=B2} DBQs=[DBQ{version=1590947504511254528,q=id:B2 OR id:B4}] [junit4] 2> 562165 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@28dba099[collection1] realtime] [junit4] 2> 562198 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={wt=json&indent=true}{add=[B6 (1590947504513351680)]} 0 232 [junit4] 2> 562198 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: REPLAYING replay: false [junit4] 2> 562199 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0 [junit4] 2> 562247 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@364221fc[collection1] realtime] [junit4] 2> 562284 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f90bcfa[collection1] realtime] [junit4] 2> 562284 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=1590947502077509632,id=B3} DBQs=[DBQ{version=1590947504511254528,q=id:B2 OR id:B4}] [junit4] 2> 562321 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@631f6eff[collection1] realtime] [junit4] 2> 562321 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=1590947502078558208,id=B4} DBQs=[DBQ{version=1590947504511254528,q=id:B2 OR id:B4}] [junit4] 2> 562395 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c4947a8[collection1] realtime] [junit4] 2> 562395 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected. Update=add{flags=a,_version_=1590947502078558209,id=B5} DBQs=[DBQ{version=1590947504511254528,q=id:B2 OR id:B4}] [junit4] 2> 562430 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@686d25b6[collection1] realtime] [junit4] 2> 562430 INFO (recoveryExecutor-830-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> 562430 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@73f7d29 commitCommandVersion:0 [junit4] 2> 562815 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@51e4142f[collection1] main] [junit4] 2> 562817 INFO (searcherExecutor-828-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51e4142f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):c1) Uninverting(_8(8.0.0):c1) Uninverting(_9(8.0.0):c1) Uninverting(_b(8.0.0):c1)))} [junit4] 2> 562817 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 562817 INFO (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[B0 (1590947501892960256), B1 (1590947501928611840), B2 (1590947501929660416), B3 (1590947502077509632), B4 (1590947502078558208), B5 (1590947502078558209)],deleteByQuery=id:B1 OR id:B3 OR id:B6 (-1590947501929660417)} 0 857 [junit4] 2> 562817 WARN (recoveryExecutor-830-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0} [junit4] 2> 562831 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id&sort=id+asc&wt=xml} hits=4 status=0 QTime=14 [junit4] 2> 562851 INFO (TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[7919C757183A1F7D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNewDBQAndDocMatchingOldDBQDuringLogReplay [junit4] 2> 562867 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDropBuffered [junit4] 2> 562868 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 562868 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3de1a68c[collection1] realtime] [junit4] 2> 562868 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] 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> 562869 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1590947505460215808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 562869 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@73f7d29 commitCommandVersion:1590947505460215808 [junit4] 2> 562928 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d25e76f[collection1] main] [junit4] 2> 562929 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 562930 INFO (searcherExecutor-828-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d25e76f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 562932 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 63 [junit4] 2> 562932 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 562932 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 562933 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C1 (5)]} 0 0 [junit4] 2> 562933 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (6)]} 0 0 [junit4] 2> 562933 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C3 (7)]} 0 0 [junit4] 2> 562933 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000007 refcount=1}} [junit4] 2> 562933 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000007 refcount=1}} [junit4] 2> 562934 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C4 (8)]} 0 0 [junit4] 2> 562934 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C5 (9)]} 0 0 [junit4] 2> 562934 WARN (recoveryExecutor-830-thread-1) [ ] o.a.s.u.UpdateLog Starting log replay tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000007 refcount=2} active=true starting pos=0 inSortedOrder=false [junit4] 2> 562935 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 562935 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@73f7d29 commitCommandVersion:0 [junit4] 2> 563086 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@432fe06[collection1] main] [junit4] 2> 563087 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 563088 INFO (searcherExecutor-828-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@432fe06[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(8.0.0):c2)))} [junit4] 2> 563088 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C4 (8), C5 (9)]} 0 154 [junit4] 2> 563088 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.UpdateLog Re-computing max version from index after log re-play. [junit4] 2> 563102 WARN (recoveryExecutor-830-thread-1) [ ] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0} [junit4] 2> 563118 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0 [junit4] 2> 563119 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C100 (10)]} 0 0 [junit4] 2> 563119 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C101 (11)]} 0 0 [junit4] 2> 563119 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000008 refcount=1}} [junit4] 2> 563120 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C103 (12)]} 0 0 [junit4] 2> 563120 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C104 (13)]} 0 0 [junit4] 2> 563120 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000008 refcount=1}} [junit4] 2> 563120 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000008 refcount=1}} [junit4] 2> 563120 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C105 (14)]} 0 0 [junit4] 2> 563120 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C106 (15)]} 0 0 [junit4] 2> 563122 WARN (recoveryExecutor-830-thread-1) [ ] o.a.s.u.UpdateLog Starting log replay tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000008 refcount=2} active=true starting pos=88 inSortedOrder=false [junit4] 2> 563199 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 563199 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@73f7d29 commitCommandVersion:0 [junit4] 2> 563270 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e6a37d2[collection1] main] [junit4] 2> 563271 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 563272 INFO (searcherExecutor-828-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e6a37d2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(8.0.0):c2) Uninverting(_d(8.0.0):c4)))} [junit4] 2> 563284 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C105 (14), C106 (15)]} 0 162 [junit4] 2> 563284 INFO (recoveryExecutor-830-thread-1) [ ] o.a.s.u.UpdateLog Re-computing max version from index after log re-play. [junit4] 2> 563287 WARN (recoveryExecutor-830-thread-1) [ ] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=88} [junit4] 2> 563301 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] 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=13 [junit4] 2> 563302 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0 [junit4] 2> 563302 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 563302 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (18)]} 0 0 [junit4] 2> 563302 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (19)]} 0 0 [junit4] 2> 563302 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000009 refcount=1}} [junit4] 2> 563303 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (16)]} 0 0 [junit4] 2> 563303 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (17)]} 0 0 [junit4] 2> 563303 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1590947505915297792,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 563303 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@73f7d29 commitCommandVersion:1590947505915297792 [junit4] 2> 563465 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@160f51e9[collection1] main] [junit4] 2> 563465 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 563499 INFO (searcherExecutor-828-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@160f51e9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(8.0.0):c2) Uninverting(_d(8.0.0):c4) Uninverting(_e(8.0.0):c2)))} [junit4] 2> 563499 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 196 [junit4] 2> 563500 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0 [junit4] 2> 563502 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] 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=1 [junit4] 2> 563535 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (17)]} 0 15 [junit4] 2> 563536 INFO (TEST-TestRecovery.testDropBuffered-seed#[7919C757183A1F7D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDropBuffered [junit4] 2> 563572 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBufferingFlags [junit4] 2> 563572 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 563573 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c362aaf[collection1] realtime] [junit4] 2> 563573 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] 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> 563573 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1590947506198413312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 563573 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@73f7d29 commitCommandVersion:1590947506198413312 [junit4] 2> 563609 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@45f74561[collection1] main] [junit4] 2> 563609 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 563611 INFO (searcherExecutor-828-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@45f74561[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 563611 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 38 [junit4] 2> 563644 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 563645 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[Q1 (20)]} 0 0 [junit4] 2> 563645 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[Q2 (21)]} 0 0 [junit4] 2> 563645 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[Q3 (22)]} 0 0 [junit4] 2> 563645 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1625037932 [junit4] 2> 563645 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 563645 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2be07f63: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1ce71a0 [junit4] 2> 563681 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 563681 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c7ab8a9: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b934925 [junit4] 2> 563697 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 563697 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@153acd23: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@40c7bb53 [junit4] 2> 563736 INFO (coreCloseExecutor-833-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3c18efc5 [junit4] 2> 563736 INFO (coreCloseExecutor-833-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1008267205 [junit4] 2> 563736 INFO (coreCloseExecutor-833-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d659b7f: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@46d7b062 [junit4] 2> 563828 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 563938 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 563989 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 564338 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 564638 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 564673 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 564673 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62f3b1e0 [junit4] 2> 564675 INFO (coreLoadExecutor-840-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 564675 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 564749 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 564856 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 565100 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 565135 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 565136 INFO (coreLoadExecutor-840-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@62f3b1e0 [junit4] 2> 565136 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 565136 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/] [junit4] 2> 565142 WARN (coreLoadExecutor-840-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> 565288 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 565288 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 565309 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 565309 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 565310 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1599790333, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 565310 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@52eb002f[collection1] main] [junit4] 2> 565311 WARN (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 565311 INFO (coreLoadExecutor-840-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 565335 INFO (searcherExecutor-841-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@52eb002f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 565382 WARN (recoveryExecutor-843-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_7919C757183A1F7D-001/init-core-data-001/tlog/tlog.0000000000000000011 refcount=2} active=false starting pos=0 inSortedOrder=false [junit4] 2> 565416 INFO (recoveryExecutor-843-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> 565416 INFO (recoveryExecutor-843-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@731bedbf commitCommandVersion:0 [junit4] 2> 565477 INFO (recoveryExecutor-843-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@33a0e50b[collection1] main] [junit4] 2> 565479 INFO (searcherExecutor-841-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@33a0e50b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_g(8.0.0):c3)))} [junit4] 2> 565479 INFO (recoveryExecutor-843-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 565479 INFO (recoveryExecutor-843-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[Q1 (20), Q2 (21), Q3 (22)]} 0 97 [junit4] 2> 565479 WARN (recoveryExecutor-843-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0} [junit4] 2> 565479 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=805963130 [junit4] 2> 565479 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 565479 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d4e9d47: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@11076fb9 [junit4] 2> 565506 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 565506 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70067081: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b934925 [junit4] 2> 565527 INFO (TEST-TestRecovery.testBufferingFlags-seed#[7919C757183A1F7D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=nu [...truncated too long message...] stry=solr.core.hdfsbackuprestore_restored.shard1_1.replica_t135, tag=1244106974 [junit4] 2> 877458 INFO (coreCloseExecutor-1428-thread-14) [n:127.0.0.1:38786_solr c:hdfsbackuprestore_restored s:shard1_1 r:core_node136 x:hdfsbackuprestore_restored_shard1_1_replica_t135] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3382f8ca: rootName = null, domain = solr.core.hdfsbackuprestore_restored.shard1_1.replica_t135, service url = null, agent id = null] for registry solr.core.hdfsbackuprestore_restored.shard1_1.replica_t135 / com.codahale.metrics.MetricRegistry@5f0601f7 [junit4] 2> 877532 INFO (coreCloseExecutor-1428-thread-15) [n:127.0.0.1:38786_solr c:hdfsbackuprestore_restored s:shard1_0 r:core_node142 x:hdfsbackuprestore_restored_shard1_0_replica_t141] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.hdfsbackuprestore_restored.shard1_0.replica_t141, tag=289870863 [junit4] 2> 877677 INFO (coreCloseExecutor-1427-thread-13) [n:127.0.0.1:40688_solr c:hdfsbackuprestore_restored s:shard1_0 r:core_node144 x:hdfsbackuprestore_restored_shard1_0_replica_p143] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore_restored.shard1_0.leader, tag=390204631 [junit4] 2> 877690 INFO (coreCloseExecutor-1428-thread-15) [n:127.0.0.1:38786_solr c:hdfsbackuprestore_restored s:shard1_0 r:core_node142 x:hdfsbackuprestore_restored_shard1_0_replica_t141] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70d18f9: rootName = null, domain = solr.core.hdfsbackuprestore_restored.shard1_0.replica_t141, service url = null, agent id = null] for registry solr.core.hdfsbackuprestore_restored.shard1_0.replica_t141 / com.codahale.metrics.MetricRegistry@7ef283ef [junit4] 2> 877691 INFO (coreCloseExecutor-1427-thread-2) [n:127.0.0.1:40688_solr c:hdfsbackuprestore s:shard1 r:core_node5 x:hdfsbackuprestore_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore.shard1.leader, tag=158105056 [junit4] 2> 877691 INFO (coreCloseExecutor-1427-thread-6) [n:127.0.0.1:40688_solr c:hdfsbackuprestore s:shard1_1 r:core_node24 x:hdfsbackuprestore_shard1_1_replica0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore.shard1_1.leader, tag=1193097904 [junit4] 2> 877825 WARN (indexFetcher-1301-thread-1) [n:127.0.0.1:38786_solr c:hdfsbackuprestore s:shard2 r:core_node15 x:hdfsbackuprestore_shard2_replica_t12] o.a.s.h.ReplicationHandler I was asked to replicate but CoreContainer is shutting down [junit4] 2> 877964 INFO (coreCloseExecutor-1428-thread-15) [n:127.0.0.1:38786_solr c:hdfsbackuprestore_restored s:shard1_0 r:core_node142 x:hdfsbackuprestore_restored_shard1_0_replica_t141] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore_restored.shard1_0.leader, tag=289870863 [junit4] 2> 877964 INFO (coreCloseExecutor-1428-thread-7) [n:127.0.0.1:38786_solr c:hdfsbackuprestore s:shard1_0 r:core_node21 x:hdfsbackuprestore_shard1_0_replica0] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore.shard1_0.leader, tag=174548983 [junit4] 2> 877965 INFO (coreCloseExecutor-1428-thread-4) [n:127.0.0.1:38786_solr c:hdfsbackuprestore s:shard2 r:core_node15 x:hdfsbackuprestore_shard2_replica_t12] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore.shard2.leader, tag=1496666451 [junit4] 2> 878128 INFO (coreCloseExecutor-1428-thread-2) [n:127.0.0.1:38786_solr c:hdfsbackuprestore s:shard2 r:core_node11 x:hdfsbackuprestore_shard2_replica_n9] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore.shard2.leader, tag=677224548 [junit4] 2> 878140 INFO (coreCloseExecutor-1428-thread-12) [n:127.0.0.1:38786_solr c:hdfsbackuprestore_restored s:shard1_0 r:core_node126 x:hdfsbackuprestore_restored_shard1_0_replica_n125] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore_restored.shard1_0.leader, tag=46568512 [junit4] 2> 878141 INFO (coreCloseExecutor-1428-thread-14) [n:127.0.0.1:38786_solr c:hdfsbackuprestore_restored s:shard1_1 r:core_node136 x:hdfsbackuprestore_restored_shard1_1_replica_t135] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.hdfsbackuprestore_restored.shard1_1.leader, tag=1244106974 [junit4] 2> 878446 INFO (jetty-closer-2504-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72169027938418693-127.0.0.1:40688_solr-n_0000000000) closing [junit4] 2> 878446 INFO (OverseerStateUpdate-72169027938418693-127.0.0.1:40688_solr-n_0000000000) [n:127.0.0.1:40688_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:40688_solr [junit4] 2> 878590 WARN (OverseerAutoScalingTriggerThread-72169027938418693-127.0.0.1:40688_solr-n_0000000000) [n:127.0.0.1:40688_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 878734 INFO (zkCallback-2521-thread-4-processing-n:127.0.0.1:38786_solr) [n:127.0.0.1:38786_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38786_solr [junit4] 2> 878788 INFO (jetty-closer-2504-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@77f446c2{/solr,null,UNAVAILABLE} [junit4] 2> 879039 INFO (jetty-closer-2504-thread-1) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 880270 INFO (jetty-closer-2504-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3585e637{/solr,null,UNAVAILABLE} [junit4] 2> 880271 INFO (jetty-closer-2504-thread-2) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 880288 ERROR (SUITE-TestHdfsCloudBackupRestore-seed#[7919C757183A1F7D]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 880288 INFO (SUITE-TestHdfsCloudBackupRestore-seed#[7919C757183A1F7D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40862 40862 [junit4] 2> 880429 INFO (Thread-926) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40862 40862 [junit4] 2> 880430 WARN (Thread-926) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 9 /solr/collections/hdfsbackuprestore/terms/shard1_1 [junit4] 2> 9 /solr/collections/hdfsbackuprestore_restored/terms/shard2 [junit4] 2> 9 /solr/collections/hdfsbackuprestore/terms/shard1 [junit4] 2> 9 /solr/collections/hdfsbackuprestore_restored/terms/shard1_1 [junit4] 2> 9 /solr/collections/hdfsbackuprestore_restored/terms/shard1_0 [junit4] 2> 8 /solr/collections/hdfsbackuprestore/terms/shard1_0 [junit4] 2> 7 /solr/collections/hdfsbackuprestore/terms/shard2 [junit4] 2> 4 /solr/aliases.json [junit4] 2> 2 /solr/security.json [junit4] 2> 2 /solr/configs/customConfigName [junit4] 2> 2 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 77 /solr/collections/hdfsbackuprestore_restored/state.json [junit4] 2> 4 /solr/clusterprops.json [junit4] 2> 4 /solr/clusterstate.json [junit4] 2> 2 /solr/collections/hdfsbackuprestore_restored/leader_elect/shard1_0/election/72169027938418694-core_node126-n_0000000000 [junit4] 2> 2 /solr/collections/hdfsbackuprestore_restored/leader_elect/shard1_1/election/72169027938418694-core_node124-n_0000000000 [junit4] 2> 2 /solr/collections/hdfsbackuprestore/leader_elect/shard1_1/election/72169027938418694-core_node20-n_0000000000 [junit4] 2> 2 /solr/collections/hdfsbackuprestore/state.json [junit4] 2> 2 /solr/collections/hdfsbackuprestore_restored/leader_elect/shard2/election/72169027938418694-core_node122-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 4 /solr/live_nodes [junit4] 2> 4 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestHdfsCloudBackupRestore_7919C757183A1F7D-001 [junit4] 2> Jan 29, 2018 5:14:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1ace44e8), locale=es-VE, timezone=Europe/Athens [junit4] 2> NOTE: Linux 4.4.0-104-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=2,free=255209680,total=520093696 [junit4] 2> NOTE: All tests run in this JVM: [SolrMetricsIntegrationTest, TestPointFields, TestExecutePlanAction, TestConfigSets, TestFieldSortValues, ReturnFieldsTest, VMParamsZkACLAndCredentialsProvidersTest, LargeFieldTest, TestDistribDocBasedVersion, DateFieldTest, TestReloadDeadlock, TestRangeQuery, SpellPossibilityIteratorTest, SpatialRPTFieldTypeTest, MoveReplicaHDFSFailoverTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestLeaderElectionWithEmptyReplica, SpellCheckCollatorWithCollapseTest, TestSQLHandler, UniqFieldsUpdateProcessorFactoryTest, TestPayloadScoreQParserPlugin, TestSlowCompositeReaderWrapper, TestQueryTypes, CollectionsAPISolrJTest, TestRuleBasedAuthorizationPlugin, TestSolrQueryResponse, AliasIntegrationTest, TestHdfsCloudBackupRestore] [junit4] Completed [110/772 (2!)] on J2 in 86.12s, 1 test, 1 error <<< FAILURES! [...truncated 50286 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org