Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-MacOSX/48/ Java: 64bit/jdk-13.0.2 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.handler.V2ApiIntegrationTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.handler.V2ApiIntegrationTest: 1) Thread[id=24413, name=SessionTracker, state=TIMED_WAITING, group=TGRP-V2ApiIntegrationTest] at [email protected]/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.V2ApiIntegrationTest: 1) Thread[id=24413, name=SessionTracker, state=TIMED_WAITING, group=TGRP-V2ApiIntegrationTest] at [email protected]/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) at __randomizedtesting.SeedInfo.seed([A6856965BEC63311]:0) FAILED: org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete Error Message: hard529 wasn't fast enough Stack Trace: java.lang.AssertionError: hard529 wasn't fast enough at __randomizedtesting.SeedInfo.seed([A6856965BEC63311:61C9D1F8A56EFEA1]:0) at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.assertTrue(Assert.java:41) at org.junit.Assert.assertNotNull(Assert.java:712) at org.apache.solr.update.SoftAutoCommitTest.doTestSoftAndHardCommitMaxTimeDelete(SoftAutoCommitTest.java:358) at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete(SoftAutoCommitTest.java:298) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:567) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:830) Build Log: [...truncated 14778 lines...] [junit4] Suite: org.apache.solr.update.SoftAutoCommitTest [junit4] 2> 1080191 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/server/solr/configsets/_default/conf' [junit4] 2> 1080191 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1080192 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J2/temp/solr.update.SoftAutoCommitTest_A6856965BEC63311-001/data-dir-79-001 [junit4] 2> 1080192 WARN (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=18 numCloses=18 [junit4] 2> 1080192 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1080194 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth [junit4] 2> 1080194 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 1080195 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1080222 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.1 [junit4] 2> 1080245 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 1080336 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1080565 WARN (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr]. Features requiring this directory may fail. [junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/filestore" "write") [junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) [junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/filestore" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?] [junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:1036) ~[?:?] [junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?] [junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?] [junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?] [junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?] [junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?] [junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?] [junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?] [junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:634) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:806) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:796) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:646) ~[java/:?] [junit4] 2> at org.apache.solr.update.SoftAutoCommitTest.beforeClass(SoftAutoCommitTest.java:66) ~[test/:?] [junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?] [junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?] [junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:567) ~[?:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?] [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?] [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?] [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?] [junit4] 2> at java.lang.Thread.run(Thread.java:830) [?:?] [junit4] 2> 1080567 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1080569 WARN (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@209dcab6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1080569 WARN (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@209dcab6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1080573 WARN (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5ee383a3[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1080573 WARN (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5ee383a3[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1080574 WARN (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info [junit4] 2> 1080600 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1080600 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1080630 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70153fd1 [junit4] 2> 1080660 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70153fd1 [junit4] 2> 1080660 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70153fd1 [junit4] 2> 1080664 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1080681 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.1 [junit4] 2> 1080705 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1080839 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1080854 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 1080854 INFO (coreLoadExecutor-3465-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@70153fd1 [junit4] 2> 1080855 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J2/temp/solr.update.SoftAutoCommitTest_A6856965BEC63311-001/data-dir-79-001/] [junit4] 2> 1080856 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1080918 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1080918 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1080919 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1080919 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1080920 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1080920 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c375efe[collection1] main] [junit4] 2> 1080921 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1081001 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1081159 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1081159 INFO (coreLoadExecutor-3465-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1663510770846007296 [junit4] 2> 1081161 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1081162 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1081162 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1081162 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 1081163 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1081164 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1081165 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1081166 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 1081167 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 1081168 INFO (searcherExecutor-3466-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2c375efe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1081170 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 1081201 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftCommitWithinAndHardCommitMaxTimeMixedAdds [junit4] 2> 1089618 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1663510779707523072)]} 0 8 [junit4] 2> 1090118 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1090139 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@656ecc97[collection1] main] [junit4] 2> 1090140 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1090142 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@656ecc97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447506197}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1090817 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1090817 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1090820 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5431774e[collection1] realtime] [junit4] 2> 1090820 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1090821 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[530 (1663510780976300032)]} 0 1 [junit4] 2> 1091322 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1091324 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@26d09fb4[collection1] main] [junit4] 2> 1091324 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1091326 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26d09fb4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447506197}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1092031 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1092031 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1092032 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5eda6495[collection1] realtime] [junit4] 2> 1092032 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1093042 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftCommitWithinAndHardCommitMaxTimeMixedAdds [junit4] 2> 1093053 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeDelete [junit4] 2> 1098069 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1663510788566941696)]} 0 9 [junit4] 2> 1098070 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1663510788578476032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1098070 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:1663510788578476032 [junit4] 2> 1098087 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5bc24930[collection1] main] [junit4] 2> 1098087 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1098089 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5bc24930[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447514139}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1098093 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 22 [junit4] 2> 1098094 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[529 (-1663510788602593280)]} 0 1 [junit4] 2> 1098596 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1098597 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14fb8d44[collection1] main] [junit4] 2> 1098599 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14fb8d44[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1098600 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1098601 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[550 (1663510789134221312)]} 0 1 [junit4] 2> 1099106 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1099111 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a272b14[collection1] main] [junit4] 2> 1099112 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1099113 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3a272b14[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447515171}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1106719 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1106719 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1106725 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeDelete [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SoftAutoCommitTest -Dtests.method=testSoftAndHardCommitMaxTimeDelete -Dtests.seed=A6856965BEC63311 -Dtests.slow=true -Dtests.locale=yi -Dtests.timezone=Europe/Rome -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 13.7s J2 | SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete <<< [junit4] > Throwable #1: java.lang.AssertionError: hard529 wasn't fast enough [junit4] > at __randomizedtesting.SeedInfo.seed([A6856965BEC63311:61C9D1F8A56EFEA1]:0) [junit4] > at org.apache.solr.update.SoftAutoCommitTest.doTestSoftAndHardCommitMaxTimeDelete(SoftAutoCommitTest.java:358) [junit4] > at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete(SoftAutoCommitTest.java:298) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567) [junit4] > at java.base/java.lang.Thread.run(Thread.java:830) [junit4] 2> 1106751 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@28413157[collection1] realtime] [junit4] 2> 1106752 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1106773 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeRapidAdds [junit4] 2> 1111786 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5000 (1663510802957598720)]} 0 2 [junit4] 2> 1111787 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5001 (1663510802961793024)]} 0 0 [junit4] 2> 1111788 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5002 (1663510802962841600)]} 0 0 [junit4] 2> 1111788 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5003 (1663510802962841601)]} 0 0 [junit4] 2> 1111789 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5004 (1663510802963890176)]} 0 0 [junit4] 2> 1111790 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 1136327073723615ns - 1136327067020976ns == 6ms [junit4] 2> 1111790 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1 [junit4] 2> 1111790 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1 [junit4] 2> 1112296 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1112298 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3dbf3294[collection1] main] [junit4] 2> 1112298 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1112299 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3dbf3294[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447515171}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447528359}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1112990 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1112990 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1112991 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4db19c5e[collection1] realtime] [junit4] 2> 1112991 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1118410 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeRapidAdds [junit4] 2> 1118452 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHardCommitWithinAndSoftCommitMaxTimeRapidAdds [junit4] 2> 1123457 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5000 (1663510815197626368)]} 0 1 [junit4] 2> 1123458 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5001 (1663510815199723520)]} 0 0 [junit4] 2> 1123458 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5002 (1663510815199723521)]} 0 0 [junit4] 2> 1123459 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5003 (1663510815200772096)]} 0 0 [junit4] 2> 1123459 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5004 (1663510815200772097)]} 0 0 [junit4] 2> 1123459 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 1136338743338894ns - 1136338740092518ns == 3ms [junit4] 2> 1123459 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1 [junit4] 2> 1123459 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1 [junit4] 2> 1123959 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1123965 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e074e14[collection1] main] [junit4] 2> 1123965 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1123967 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6e074e14[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447515171}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447540025}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1124658 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1124658 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1124664 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@33e929d2[collection1] realtime] [junit4] 2> 1124664 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1130080 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHardCommitWithinAndSoftCommitMaxTimeRapidAdds [junit4] 2> 1130088 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftCommitWithinAndHardCommitMaxTimeRapidAdds [junit4] 2> 1135091 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5000 (1663510827395710976)]} 0 2 [junit4] 2> 1135092 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5001 (1663510827398856704)]} 0 0 [junit4] 2> 1135092 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5002 (1663510827398856705)]} 0 0 [junit4] 2> 1135093 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5003 (1663510827399905280)]} 0 0 [junit4] 2> 1135095 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5004 (1663510827402002432)]} 0 0 [junit4] 2> 1135096 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 1136350379671191ns - 1136350373132691ns == 6ms [junit4] 2> 1135096 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1 [junit4] 2> 1135096 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1 [junit4] 2> 1135599 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1135604 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@22497a34[collection1] main] [junit4] 2> 1135604 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1135607 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@22497a34[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447515171}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1136292 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1136292 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1136292 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7058a7ce[collection1] realtime] [junit4] 2> 1136293 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1141715 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftCommitWithinAndHardCommitMaxTimeRapidAdds [junit4] 2> 1141723 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxDocs [junit4] 2> 1149748 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8000 (1663510842761543680)]} 0 5 [junit4] 2> 1149748 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8001 (1663510842766786560)]} 0 0 [junit4] 2> 1149749 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8002 (1663510842767835136)]} 0 0 [junit4] 2> 1149750 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8003 (1663510842768883712)]} 0 0 [junit4] 2> 1149750 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8004 (1663510842768883713)]} 0 0 [junit4] 2> 1149752 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8005 (1663510842769932288)]} 0 0 [junit4] 2> 1149753 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8006 (1663510842770980864)]} 0 0 [junit4] 2> 1149755 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1149758 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8007 (1663510842776223744)]} 0 1 [junit4] 2> 1149759 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1149768 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d98ef4e[collection1] main] [junit4] 2> 1149768 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1149768 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1149770 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7d98ef4e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447515171}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1149796 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@345212ab[collection1] realtime] [junit4] 2> 1149812 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1150802 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxDocs [junit4] 2> 1150871 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftCommitWithinAndHardCommitMaxTimeDelete [junit4] 2> 1155877 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1663510849192460288)]} 0 1 [junit4] 2> 1155878 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1663510849194557440,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1155878 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:1663510849194557440 [junit4] 2> 1155881 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1899cea2[collection1] main] [junit4] 2> 1155882 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1155884 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1899cea2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447515171}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_9(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447571941}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1155885 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7 [junit4] 2> 1155886 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[529 (-1663510849201897472)]} 0 0 [junit4] 2> 1156388 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1156392 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6f20eb8e[collection1] main] [junit4] 2> 1156393 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1156396 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6f20eb8e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_3(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447515171}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1156397 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[550 (1663510849736671232)]} 0 2 [junit4] 2> 1156917 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1156921 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4122b79e[collection1] main] [junit4] 2> 1156921 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1156925 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4122b79e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447572981}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1157216 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1157216 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1157239 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c369f17[collection1] realtime] [junit4] 2> 1157286 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1163368 INFO (TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftCommitWithinAndHardCommitMaxTimeDelete [junit4] 2> 1163384 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHardCommitWithinAndSoftCommitMaxTimeMixedAdds [junit4] 2> 1171813 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1663510865901518848)]} 0 1 [junit4] 2> 1172314 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1172321 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7de898e8[collection1] main] [junit4] 2> 1172322 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1172325 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7de898e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447507385}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447572981}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447588380}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1173017 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1173017 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1173018 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2390d931[collection1] realtime] [junit4] 2> 1173020 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1173024 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[530 (1663510867167150080)]} 0 6 [junit4] 2> 1173525 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1173531 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@415f9126[collection1] main] [junit4] 2> 1173532 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1173538 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@415f9126[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447572981}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447588380}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447589590}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1174227 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1174227 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1174231 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@46f5ee69[collection1] realtime] [junit4] 2> 1174231 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1175233 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHardCommitWithinAndSoftCommitMaxTimeMixedAdds [junit4] 2> 1175279 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHardCommitWithinAndSoftCommitMaxTimeDelete [junit4] 2> 1180292 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1663510874789249024)]} 0 4 [junit4] 2> 1180292 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1663510874794491904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1180292 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:1663510874794491904 [junit4] 2> 1180312 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2062d272[collection1] main] [junit4] 2> 1180313 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1180315 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2062d272[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447572981}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447589590}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_d(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447596364}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1180319 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 27 [junit4] 2> 1180320 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[529 (-1663510874823852032)]} 0 0 [junit4] 2> 1180824 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1180825 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a60bb3d[collection1] main] [junit4] 2> 1180826 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1180827 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[550 (1663510875354431488)]} 0 1 [junit4] 2> 1180829 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a60bb3d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447572981}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447589590}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1181329 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1181332 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29ca3ca1[collection1] main] [junit4] 2> 1181332 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1181334 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@29ca3ca1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447589590}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_e(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447597393}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1181521 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1181521 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1181523 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ab15d33[collection1] realtime] [junit4] 2> 1181550 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1187535 INFO (TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHardCommitWithinAndSoftCommitMaxTimeDelete [junit4] 2> 1187609 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeMixedAdds [junit4] 2> 1196694 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1663510891989041152)]} 0 3 [junit4] 2> 1197194 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1197196 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d820535[collection1] main] [junit4] 2> 1197196 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1197198 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7d820535[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_c(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447589590}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_e(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447597393}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_f(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447613257}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1198755 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1198755 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1198756 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dd7e002[collection1] realtime] [junit4] 2> 1198757 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1198759 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[530 (1663510894155399168)]} 0 3 [junit4] 2> 1199261 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 1199269 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a68181d[collection1] main] [junit4] 2> 1199269 INFO (commitScheduler-3470-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1199271 INFO (searcherExecutor-3466-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a68181d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.5.1):c5:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447551663}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.5.1):c7:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565825}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447565856}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_e(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447597393}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_f(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447613257}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_g(8.5.1):c1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.2, java.vm.version=13.0.2+8, lucene.version=8.5.1, os.arch=x86_64, java.runtime.version=13.0.2+8, source=flush, os.version=10.14.6, timestamp=1586447615327}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1199969 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1199969 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5a64a706 commitCommandVersion:0 [junit4] 2> 1199969 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@d2f66f[collection1] realtime] [junit4] 2> 1199970 INFO (commitScheduler-3469-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1200980 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[A6856965BEC63311]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeMixedAdds [junit4] 2> 1200980 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 1200981 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1456429857 [junit4] 2> 1200982 INFO (coreCloseExecutor-3471-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@32adaf8 [junit4] 2> 1200982 INFO (coreCloseExecutor-3471-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@32adaf8 [junit4] 2> 1200982 INFO (coreCloseExecutor-3471-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@30e2fc20: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@681b888a [junit4] 2> 1200996 INFO (coreCloseExecutor-3471-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 1200999 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1200999 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@26508743: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6010e11b [junit4] 2> 1201004 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1201004 INFO (SUITE-SoftAutoCommitTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters [...truncated too long message...] er-8530-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@abfdb9b{/solr,null,UNAVAILABLE} [junit4] 2> 1909818 INFO (jetty-closer-8530-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1909820 INFO (SUITE-V2ApiIntegrationTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer. [junit4] 2> 1910029 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 5 /solr/collections/collection1/terms/shard1 [junit4] 2> 3 /solr/aliases.json [junit4] 2> 3 /solr/clusterprops.json [junit4] 2> 2 /solr/configs/conf1/managed-schema [junit4] 2> 2 /solr/packages.json [junit4] 2> 2 /solr/security.json [junit4] 2> 2 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 16 /solr/collections/collection1/state.json [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 3 /solr/clusterstate.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 19 /solr/overseer/queue [junit4] 2> 9 /solr/overseer/collection-queue-work [junit4] 2> 6 /solr/live_nodes [junit4] 2> 6 /solr/collections [junit4] 2> [junit4] 2> 1910033 INFO (SUITE-V2ApiIntegrationTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:61547 [junit4] 2> 1910033 INFO (SUITE-V2ApiIntegrationTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61547 [junit4] 2> 1910034 INFO (SUITE-V2ApiIntegrationTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61547 [junit4] 2> 1910034 INFO (SUITE-V2ApiIntegrationTest-seed#[A6856965BEC63311]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Apr 09, 2020 4:05:26 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Apr 09, 2020 4:05:36 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.handler.V2ApiIntegrationTest: [junit4] 2> 1) Thread[id=24413, name=SessionTracker, state=TIMED_WAITING, group=TGRP-V2ApiIntegrationTest] [junit4] 2> at [email protected]/java.lang.Thread.sleep(Native Method) [junit4] 2> at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) [junit4] 2> Apr 09, 2020 4:05:36 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=24413, name=SessionTracker, state=TIMED_WAITING, group=TGRP-V2ApiIntegrationTest] [junit4] 2> 1920317 ERROR (SessionTracker) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : SessionTracker [junit4] 2> => java.lang.InterruptedException: sleep interrupted [junit4] 2> at java.base/java.lang.Thread.sleep(Native Method) [junit4] 2> java.lang.InterruptedException: sleep interrupted [junit4] 2> at java.lang.Thread.sleep(Native Method) [?:?] [junit4] 2> at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) ~[zookeeper-3.5.5.jar:3.5.5] [junit4] 2> Apr 09, 2020 4:05:36 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@48e58cae), locale=cy-GB, timezone=America/North_Dakota/New_Salem [junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 13.0.2 (64-bit)/cpus=6,threads=1,free=216518592,total=423624704 [junit4] 2> NOTE: All tests run in this JVM: [BlockJoinFacetRandomTest, AlternateDirectoryTest, TestRequestStatusCollectionAPI, SolrGraphiteReporterTest, MetricsHistoryWithAuthIntegrationTest, TestIBSimilarityFactory, TestSolrCloudWithKerberosAlt, PropertiesRequestHandlerTest, HdfsUnloadDistributedZkTest, DistributedSpellCheckComponentTest, NodeLostTriggerIntegrationTest, TestInitQParser, TestBulkSchemaConcurrent, TokenizerChainTest, TestNRTOpen, TestValueSourceCache, TestReplicationHandler, TestNestedUpdateProcessor, AnalysisErrorHandlingTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestSimExtremeIndexing, TestDirectoryFactory, TestSlowCompositeReaderWrapper, OrderedExecutorTest, TestCollectionAPI, TestRawResponseWriter, CollectionReloadTest, TestGeoJSONResponseWriter, TestInPlaceUpdateWithRouteField, TestHashPartitioner, TestStreamBody, SearchHandlerTest, TestDistribPackageStore, ManagedSchemaRoundRobinCloudTest, TestStressUserVersions, HighlighterTest, RollingRestartTest, TestSolrTestCaseJ4, SpellCheckCollatorWithCollapseTest, IndexSizeTriggerTest, DistributedFacetPivotWhiteBoxTest, CreateCollectionCleanupTest, TestOrdValues, TestConfigSets, MoveReplicaTest, TestDynamicFieldCollectionResource, TestSimPolicyCloud, AnalyticsQueryTest, MetricsConfigTest, SortSpecParsingTest, JWTAuthPluginTest, AssignBackwardCompatibilityTest, DeleteReplicaTest, TestJsonFacetsStatsParsing, TestSerializedLuceneMatchVersion, TestCharFilters, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestFieldCacheReopen, DirectSolrConnectionTest, TestPointFields, TaggingAttributeTest, UniqFieldsUpdateProcessorFactoryTest, SolrInfoBeanTest, BitVectorTest, HdfsBasicDistributedZkTest, HttpPartitionWithTlogReplicasTest, LoggingHandlerTest, TestFieldTypeResource, LeaderFailureAfterFreshStartTest, SimpleMLTQParserTest, MoreLikeThisComponentTest, VersionInfoTest, CustomHighlightComponentTest, OverseerSolrResponseTest, SolrShardReporterTest, PeerSyncWithLeaderTest, TestLegacyField, SplitHandlerTest, TestJoin, DefaultValueUpdateProcessorTest, TestDistributedMissingSort, SolrLogAuditLoggerPluginTest, ConfigureRecoveryStrategyTest, SuggesterFSTTest, CustomCollectionTest, JSONWriterTest, DocValuesTest, CloudExitableDirectoryReaderTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, SystemCollectionCompatTest, TestCloudDeleteByQuery, TestCloudPseudoReturnFields, TestCloudRecovery, TestClusterProperties, TestConfigSetsAPI, TestDistribDocBasedVersion, TestPrepRecovery, TestSSLRandomization, TestSizeLimitedDistributedMap, TestSolrCloudWithDelegationTokens, TestTolerantUpdateProcessorCloud, VMParamsZkACLAndCredentialsProvidersTest, ZkControllerTest, ZkFailoverTest, ZkNodePropsTest, ZkShardTermsTest, AssignTest, AsyncCallRequestStatusResponseTest, ExecutePlanActionTest, TriggerCooldownIntegrationTest, TestSimComputePlanAction, TestSimDistribStateManager, TestSimExecutePlanAction, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, CdcrBootstrapTest, RequestHandlersTest, SolrCoreTest, TestSolrDeletionPolicy1, TestSolrDeletionPolicy2, TestSolrXml, JsonLoaderTest, MoreLikeThisHandlerTest, PingRequestHandlerTest, TestHdfsBackupRestoreCore, V2ApiIntegrationTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=V2ApiIntegrationTest -Dtests.seed=A6856965BEC63311 -Dtests.slow=true -Dtests.locale=cy-GB -Dtests.timezone=America/North_Dakota/New_Salem -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | V2ApiIntegrationTest (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.V2ApiIntegrationTest: [junit4] > 1) Thread[id=24413, name=SessionTracker, state=TIMED_WAITING, group=TGRP-V2ApiIntegrationTest] [junit4] > at [email protected]/java.lang.Thread.sleep(Native Method) [junit4] > at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) [junit4] > at __randomizedtesting.SeedInfo.seed([A6856965BEC63311]:0) [junit4] Completed [643/900 (2!)] on J0 in 37.04s, 7 tests, 1 error <<< FAILURES! [...truncated 48895 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-MacOSX/48/consoleText [repro] Revision: edb9fc409398f2c3446883f9f80595c884d245d0 [repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" [repro] Repro line: ant test -Dtestcase=SoftAutoCommitTest -Dtests.method=testSoftAndHardCommitMaxTimeDelete -Dtests.seed=A6856965BEC63311 -Dtests.slow=true -Dtests.locale=yi -Dtests.timezone=Europe/Rome -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] Repro line: ant test -Dtestcase=V2ApiIntegrationTest -Dtests.seed=A6856965BEC63311 -Dtests.slow=true -Dtests.locale=cy-GB -Dtests.timezone=America/North_Dakota/New_Salem -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] JUnit rest result XML files will be moved to: ./repro-reports [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr/core [repro] V2ApiIntegrationTest [repro] SoftAutoCommitTest [repro] ant compile-test [...truncated 3445 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.V2ApiIntegrationTest|*.SoftAutoCommitTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=A6856965BEC63311 -Dtests.slow=true -Dtests.locale=cy-GB -Dtests.timezone=America/North_Dakota/New_Salem -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [...truncated 176 lines...] [repro] Failures w/original seeds: [repro] 0/5 failed: org.apache.solr.handler.V2ApiIntegrationTest [repro] 0/5 failed: org.apache.solr.update.SoftAutoCommitTest [repro] Exiting with code 0 [...truncated 73 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
