[JENKINS] Lucene-Solr-Tests-master - Build # 3089 - Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/3089/

1 tests failed.
FAILED:  
org.apache.solr.update.SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds

Error Message:
soft529 wasn't fast enough

Stack Trace:
java.lang.AssertionError: soft529 wasn't fast enough
        at 
__randomizedtesting.SeedInfo.seed([709ECA08866D55E0:B3791619B751829D]: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.doTestSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:209)
        at 
org.apache.solr.update.SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:174)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12615 lines...]
   [junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_709ECA08866D55E0-001/init-core-data-001
   [junit4]   2> 14214 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 14594 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] o.e.j.u.log 
Logging initialized @14676ms to org.eclipse.jetty.util.log.Slf4jLog
   [junit4]   2> 14671 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 14868 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 14906 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 15608 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Using system property solr.solr.home: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr
   [junit4]   2> 15664 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 19809 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 21304 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 25525 WARN  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.WordDelimiterFilterFactory]. Please consult documentation how to replace 
it accordingly.
   [junit4]   2> 26799 WARN  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.GeoHashField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 26870 WARN  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.LatLonType]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 26965 WARN  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.CurrencyField]. Please consult documentation how to replace it 
accordingly.
   [junit4]   2> 28054 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 37031 WARN  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@292bc5b0[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 38700 WARN  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@31435bce[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 41335 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 41405 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 43139 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@32b8a122
   [junit4]   2> 43141 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@32b8a122
   [junit4]   2> 43141 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@32b8a122
   [junit4]   2> 43606 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 44332 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 44896 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 46870 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 47128 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 47555 INFO  (coreLoadExecutor-9-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@32b8a122
   [junit4]   2> 47667 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_709ECA08866D55E0-001/init-core-data-001/]
   [junit4]   2> 48084 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7031738305480503]
   [junit4]   2> 57010 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 57010 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 57085 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 57085 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 57101 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=47, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=63.3349609375, 
floorSegmentMB=1.078125, forceMergeDeletesPctAllowed=7.136964010060918, 
segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.32221035965128914, deletesPctAllowed=24.761996953705175
   [junit4]   2> 57193 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1d0c720f[collection1] main]
   [junit4]   2> 57922 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 58864 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 60044 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 60117 INFO  (coreLoadExecutor-9-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1620822950467862528
   [junit4]   2> 60155 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 60157 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 60326 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 60326 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 60326 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 60615 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
   [junit4]   2> 60643 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 60663 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 60664 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 60665 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 60666 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 61199 INFO  
(searcherExecutor-10-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d0c720f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 72195 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[529 (1620822961179066368)]} 0 3077
   [junit4]   2> 72803 INFO  (commitScheduler-14-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 73443 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74901 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SoftAutoCommitTest 
-Dtests.method=testHardCommitWithinAndSoftCommitMaxTimeMixedAdds 
-Dtests.seed=709ECA08866D55E0 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=ru -Dtests.timezone=America/Argentina/Rio_Gallegos 
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 14.9s J0 | 
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: soft529 wasn't fast 
enough
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([709ECA08866D55E0:B3791619B751829D]:0)
   [junit4]    >        at 
org.apache.solr.update.SoftAutoCommitTest.doTestSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:209)
   [junit4]    >        at 
org.apache.solr.update.SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:174)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 75049 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testSoftCommitWithinAndHardCommitMaxTimeMixedAdds
   [junit4]   2> 75085 INFO  (commitScheduler-14-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5cbddf50[collection1] main]
   [junit4]   2> 75119 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 75119 INFO  (commitScheduler-14-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 75229 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[529 (1620822966275145728)]} 0 37
   [junit4]   2> 75254 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5cbddf50[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737233431}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 75699 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4fc897bc[collection1] realtime]
   [junit4]   2> 75735 INFO  (commitScheduler-14-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 75735 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 75914 INFO  (commitScheduler-14-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2752212f[collection1] main]
   [junit4]   2> 75915 INFO  (commitScheduler-14-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 75950 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2752212f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737234722}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 76439 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 76439 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 76457 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[530 (1620822967585865728)]} 0 15
   [junit4]   2> 77014 INFO  (commitScheduler-14-thread-2) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 77071 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@27446007[collection1] realtime]
   [junit4]   2> 77073 INFO  (commitScheduler-14-thread-2) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7e41d163[collection1] main]
   [junit4]   2> 77073 INFO  (commitScheduler-14-thread-2) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 77075 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 77133 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7e41d163[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737234722}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 77671 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 77671 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 77673 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d24b9c1[collection1] realtime]
   [junit4]   2> 77679 INFO  (commitScheduler-13-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 78688 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testSoftCommitWithinAndHardCommitMaxTimeMixedAdds
   [junit4]   2> 78710 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 83775 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5000 (1620822975216353280)]} 0 56
   [junit4]   2> 83778 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5001 (1620822975277170688)]} 0 1
   [junit4]   2> 83791 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5002 (1620822975278219264)]} 0 13
   [junit4]   2> 83793 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5003 (1620822975292899328)]} 0 0
   [junit4]   2> 83795 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5004 (1620822975294996480)]} 0 0
   [junit4]   2> 83828 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 28074643951352787ns - 
28074643841798862ns == 109ms
   [junit4]   2> 83829 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1
   [junit4]   2> 83829 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1
   [junit4]   2> 84225 INFO  (commitScheduler-14-thread-3) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 84528 INFO  (commitScheduler-14-thread-3) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2490ed0c[collection1] main]
   [junit4]   2> 84561 INFO  (commitScheduler-14-thread-3) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 84563 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2490ed0c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737234722}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737243711}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 84979 INFO  (commitScheduler-13-thread-2) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 84979 INFO  (commitScheduler-13-thread-2) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 84981 INFO  (commitScheduler-13-thread-2) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@414a54b0[collection1] realtime]
   [junit4]   2> 84981 INFO  (commitScheduler-13-thread-2) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 90699 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 90717 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
   [junit4]   2> 95885 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5000 (1620822987799265280)]} 0 166
   [junit4]   2> 95887 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5001 (1620822987974377472)]} 0 1
   [junit4]   2> 95903 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5002 (1620822987976474624)]} 0 14
   [junit4]   2> 95923 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5003 (1620822988012126208)]} 0 1
   [junit4]   2> 95924 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5004 (1620822988014223360)]} 0 0
   [junit4]   2> 95925 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 28074656048514150ns - 
28074655842143744ns == 206ms
   [junit4]   2> 95958 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1
   [junit4]   2> 95974 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1
   [junit4]   2> 96229 INFO  (commitScheduler-14-thread-4) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 96377 INFO  (commitScheduler-14-thread-4) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@21d942c9[collection1] main]
   [junit4]   2> 96395 INFO  (commitScheduler-14-thread-4) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 96399 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@21d942c9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737234722}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_4(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737255488}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 97054 INFO  (commitScheduler-13-thread-3) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 97054 INFO  (commitScheduler-13-thread-3) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 97107 INFO  (commitScheduler-13-thread-3) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@480f2d25[collection1] realtime]
   [junit4]   2> 97127 INFO  (commitScheduler-13-thread-3) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 102509 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
   [junit4]   2> 102662 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 107699 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5000 (1620823000323457024)]} 0 36
   [junit4]   2> 107737 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5001 (1620823000362254336)]} 0 37
   [junit4]   2> 107790 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5002 (1620823000455577600)]} 0 1
   [junit4]   2> 107842 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5003 (1620823000510103552)]} 0 50
   [junit4]   2> 107843 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[5004 (1620823000512200704)]} 0 0
   [junit4]   2> 107844 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 28074667967595766ns - 
28074667785824416ns == 181ms
   [junit4]   2> 107844 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1
   [junit4]   2> 107844 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1
   [junit4]   2> 108185 INFO  (commitScheduler-14-thread-5) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 108320 INFO  (commitScheduler-14-thread-5) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b380b81[collection1] main]
   [junit4]   2> 108321 INFO  (commitScheduler-14-thread-5) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 108335 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1b380b81[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737234722}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 108866 INFO  (commitScheduler-13-thread-4) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 108866 INFO  (commitScheduler-13-thread-4) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 108868 INFO  (commitScheduler-13-thread-4) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@25f26658[collection1] realtime]
   [junit4]   2> 108869 INFO  (commitScheduler-13-thread-4) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 114465 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 114509 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testSoftCommitWithinAndHardCommitMaxTimeDelete
   [junit4]   2> 119569 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[529 (1620823012749082624)]} 0 56
   [junit4]   2> 119571 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1620823012809900032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 119571 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d 
commitCommandVersion:1620823012809900032
   [junit4]   2> 119680 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@421b59a3[collection1] main]
   [junit4]   2> 119702 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@421b59a3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_6(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737278814}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 119828 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 120437 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 867
   [junit4]   2> 120529 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{delete=[529 (-1620823013720064000)]} 0 90
   [junit4]   2> 121060 INFO  (commitScheduler-14-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 121113 INFO  (commitScheduler-14-thread-7) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@68663e5c[collection1] main]
   [junit4]   2> 121114 INFO  (commitScheduler-14-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 121130 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[550 (1620823014443581440)]} 0 1
   [junit4]   2> 121295 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@68663e5c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 121711 INFO  (commitScheduler-13-thread-5) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 121711 INFO  (commitScheduler-13-thread-5) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 121732 INFO  (commitScheduler-14-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 121880 INFO  (commitScheduler-14-thread-7) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@13727e4b[collection1] main]
   [junit4]   2> 121880 INFO  (commitScheduler-14-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 121904 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13727e4b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737280919}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 122077 INFO  (commitScheduler-13-thread-5) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 127915 INFO  
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testSoftCommitWithinAndHardCommitMaxTimeDelete
   [junit4]   2> 128252 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeDelete
   [junit4]   2> 133295 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[529 (1620823027167002624)]} 0 32
   [junit4]   2> 133296 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1620823027201605632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 133296 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d 
commitCommandVersion:1620823027201605632
   [junit4]   2> 133370 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@625ddb00[collection1] main]
   [junit4]   2> 133370 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 133425 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@625ddb00[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737280919}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_8(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737292500}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 133441 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 144
   [junit4]   2> 133443 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{delete=[529 (-1620823027354697728)]} 0 1
   [junit4]   2> 133955 INFO  (commitScheduler-14-thread-8) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 134139 INFO  (commitScheduler-14-thread-8) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6454d100[collection1] main]
   [junit4]   2> 134140 INFO  (commitScheduler-14-thread-8) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 134151 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[550 (1620823028097089536)]} 0 1
   [junit4]   2> 134189 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6454d100[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_7(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737280919}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 134643 INFO  (commitScheduler-13-thread-6) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 134696 INFO  (commitScheduler-13-thread-6) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 134670 INFO  (commitScheduler-14-thread-8) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 134751 INFO  (commitScheduler-14-thread-8) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@192c3e8a[collection1] main]
   [junit4]   2> 134752 INFO  (commitScheduler-14-thread-8) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 134796 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@192c3e8a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737293901}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 134843 INFO  (commitScheduler-13-thread-6) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 140798 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeDelete
   [junit4]   2> 140815 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeMixedAdds
   [junit4]   2> 149291 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[529 (1620823043913809920)]} 0 56
   [junit4]   2> 149801 INFO  (commitScheduler-14-thread-9) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 150115 INFO  (commitScheduler-14-thread-9) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5581507e[collection1] main]
   [junit4]   2> 150136 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5581507e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737236114}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737293901}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737309279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 150150 INFO  (commitScheduler-14-thread-9) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 150494 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 150494 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 150581 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[530 (1620823045248647168)]} 0 74
   [junit4]   2> 150637 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7eabad4e[collection1] realtime]
   [junit4]   2> 150818 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 151041 INFO  (commitScheduler-14-thread-10) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 151187 INFO  (commitScheduler-14-thread-10) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@677aac9e[collection1] main]
   [junit4]   2> 151188 INFO  (commitScheduler-14-thread-10) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 151202 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@677aac9e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737293901}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_a(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737309279}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737309731}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 151811 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 151811 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 151813 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4ebbf6ed[collection1] realtime]
   [junit4]   2> 151831 INFO  (commitScheduler-13-thread-7) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 152820 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeMixedAdds
   [junit4]   2> 152829 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testHardCommitWithinAndSoftCommitMaxTimeDelete
   [junit4]   2> 157876 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[529 (1620823052936806400)]} 0 36
   [junit4]   2> 157877 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1620823052976652288,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157877 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d 
commitCommandVersion:1620823052976652288
   [junit4]   2> 158058 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@62405fcf[collection1] main]
   [junit4]   2> 158065 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@62405fcf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737293901}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737309731}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_c(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737317136}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 158061 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 158188 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 310
   [junit4]   2> 158371 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{delete=[529 (-1620823053305905152)]} 0 180
   [junit4]   2> 158917 INFO  (commitScheduler-14-thread-11) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 158955 INFO  (commitScheduler-14-thread-11) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6d5eb29a[collection1] main]
   [junit4]   2> 158974 INFO  (commitScheduler-14-thread-11) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 158981 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6d5eb29a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_9(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737293901}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737309731}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 159266 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[550 (1620823054221312000)]} 0 202
   [junit4]   2> 159593 INFO  (commitScheduler-13-thread-8) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 159593 INFO  (commitScheduler-13-thread-8) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 159594 INFO  (commitScheduler-14-thread-12) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 159649 INFO  (commitScheduler-14-thread-12) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7d35213f[collection1] main]
   [junit4]   2> 159649 INFO  (commitScheduler-14-thread-12) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 159650 INFO  (commitScheduler-13-thread-8) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 159711 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d35213f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737309731}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_d(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737318799}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 165721 INFO  
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[709ECA08866D55E0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testHardCommitWithinAndSoftCommitMaxTimeDelete
   [junit4]   2> 165836 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxDocs
   [junit4]   2> 173855 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8000 (1620823069726605312)]} 0 3
   [junit4]   2> 173945 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8001 (1620823069806297088)]} 0 17
   [junit4]   2> 173946 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8002 (1620823069826220032)]} 0 0
   [junit4]   2> 173961 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8003 (1620823069840900096)]} 0 13
   [junit4]   2> 173962 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8004 (1620823069842997248)]} 0 0
   [junit4]   2> 173981 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8005 (1620823069844045824)]} 0 17
   [junit4]   2> 173981 INFO  (commitScheduler-14-thread-13) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 173982 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8006 (1620823069862920192)]} 0 1
   [junit4]   2> 173997 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[8007 (1620823069865017344)]} 0 14
   [junit4]   2> 173997 INFO  (commitScheduler-13-thread-9) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 174020 INFO  (commitScheduler-14-thread-13) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1fca2811[collection1] main]
   [junit4]   2> 174033 INFO  (commitScheduler-13-thread-9) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@bbe468d commitCommandVersion:0
   [junit4]   2> 174036 INFO  (commitScheduler-14-thread-13) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 174079 INFO  (searcherExecutor-10-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1fca2811[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C5:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.0.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737267470}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_b(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737309731}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_d(8.0.0):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737318799}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_e(8.0.0):C6:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.0.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1545737333186}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 174101 INFO  (commitScheduler-13-thread-9) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4c3c81f[collection1] realtime]
   [junit4]   2> 174115 INFO  (commitScheduler-13-thread-9) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 175091 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[709ECA08866D55E0]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxDocs
   [junit4]   2> 175092 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 175092 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1500753082
   [junit4]   2> 175143 INFO  (coreCloseExecutor-15-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3e0bb5d9
   [junit4]   2> 175144 INFO  (coreCloseExecutor-15-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=3e0bb5d9
   [junit4]   2> 175144 INFO  (coreCloseExecutor-15-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2c3d89ee: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4f51329a
   [junit4]   2> 175747 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 175747 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@83d582c: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@d243da5
   [junit4]   2> 175765 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 175766 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@27e984f5: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@20fbad0c
   [junit4]   2> 175766 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 175766 INFO  
(SUITE-SoftAutoCommitTest-seed#[709ECA08866D55E0]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6d81d048: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@55deda03
   [junit4]   1> Done close 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@33579098[Terminated,
 pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
   [junit4]   1> Done close 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4c4b1a44[Terminated,
 pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_709ECA08866D55E0-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): 
{multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), 
subject=PostingsFormat(name=LuceneVarGapFixedInterval), 
_root_=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=FST50, 
text=PostingsFormat(name=LuceneFixedGap)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), 
_version_=DocValuesFormat(name=Lucene70), 
range_facet_i_dv=DocValuesFormat(name=Direct), 
intDvoDefault=DocValuesFormat(name=Lucene70), 
timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1696, 
maxMBSortInHeap=7.146061684467593, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@795b96e3),
 locale=ru, timezone=America/Argentina/Rio_Gallegos
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_191 (64-bit)/cpus=4,threads=1,free=181026576,total=307232768
   [junit4]   2> NOTE: All tests run in this JVM: [SoftAutoCommitTest]
   [junit4] Completed [5/838 (1!)] on J0 in 173.27s, 10 tests, 1 failure <<< 
FAILURES!

[...truncated 52129 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1: 
invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid 
part, must have at least one section (e.g., chapter, appendix, etc.)
     [java] Processed 2464 links (2015 relative) to 3226 anchors in 248 files
     [echo] Validated Links & Anchors via: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-ref-guide/bare-bones-html/

-documentation-lint:
    [jtidy] Checking for broken html (such as invalid tags)...
   [delete] Deleting directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/jtidy_tmp
     [echo] Checking for broken links...
     [exec] 
     [exec] Crawl/parse...
     [exec] 
     [exec] Verify...
     [echo] Checking for malformed docs...

jar-checksums:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

common.resolve:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1811348371
     [copy] Copying 36 files to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1811348371
   [delete] Deleting directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1811348371

resolve-example:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

resolve-server:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1389913944
     [copy] Copying 256 files to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1389913944
   [delete] Deleting directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1389913944

check-working-copy:
[ivy:cachepath] :: resolving dependencies :: 
org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath]         confs: [default]
[ivy:cachepath]         found 
org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath]         found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath]         found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath]         found org.apache.httpcomponents#httpclient;4.3.6 in 
public
[ivy:cachepath]         found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath]         found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath]         found commons-codec#commons-codec;1.6 in public
[ivy:cachepath]         found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 27ms :: artifacts dl 2ms
        ---------------------------------------------------------------------
        |                  |            modules            ||   artifacts   |
        |       conf       | number| search|dwnlded|evicted|| number|dwnlded|
        ---------------------------------------------------------------------
        |      default     |   8   |   0   |   0   |   0   ||   8   |   0   |
        ---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for 
further details.
[wc-checker] Checking working copy status...

-jenkins-base:

BUILD SUCCESSFUL
Total time: 211 minutes 29 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
        at hudson.FilePath$34.hasMatch(FilePath.java:2678)
        at hudson.FilePath$34.invoke(FilePath.java:2557)
        at hudson.FilePath$34.invoke(FilePath.java:2547)
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
                at 
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
                at 
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
                at hudson.remoting.Channel.call(Channel.java:955)
                at hudson.FilePath.act(FilePath.java:1036)
                at hudson.FilePath.act(FilePath.java:1025)
                at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
                at 
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
                at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
                at 
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
                at hudson.model.Build$BuildExecution.post2(Build.java:186)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
                at hudson.model.Run.execute(Run.java:1819)
                at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
                at 
hudson.model.ResourceController.execute(ResourceController.java:97)
                at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
        at hudson.remoting.UserRequest.perform(UserRequest.java:212)
        at hudson.remoting.UserRequest.perform(UserRequest.java:54)
        at hudson.remoting.Request$2.run(Request.java:369)
        at 
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no 
matches found within 10000
        at hudson.FilePath.act(FilePath.java:1038)
        at hudson.FilePath.act(FilePath.java:1025)
        at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
        at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
        at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
        at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
        at hudson.model.Build$BuildExecution.post2(Build.java:186)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
        at hudson.model.Run.execute(Run.java:1819)
        at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
        at hudson.model.ResourceController.execute(ResourceController.java:97)
        at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern 
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org