Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3662/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC

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

Error Message:
soft529 wasn't fast enough

Stack Trace:
java.lang.AssertionError: soft529 wasn't fast enough
        at 
__randomizedtesting.SeedInfo.seed([E291643744082204:B3459DB7F57B12A3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at 
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:111)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10575 lines...]
   [junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_E291643744082204-001/init-core-data-001
   [junit4]   2> 0    INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] o.e.j.u.log 
Logging initialized @6057ms
   [junit4]   2> 21   INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 65   INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 345  INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.c.SolrResourceLoader Using system property solr.solr.home: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr
   [junit4]   2> 361  INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib,
 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1426 INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1800 INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4779 WARN  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 4828 INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 7929 INFO  (coreLoadExecutor-6-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib,
 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 8090 INFO  (coreLoadExecutor-6-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 8173 INFO  (coreLoadExecutor-6-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 8407 WARN  (coreLoadExecutor-6-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 8412 INFO  (coreLoadExecutor-6-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 8476 INFO  (coreLoadExecutor-6-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1
   [junit4]   2> 9051 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_E291643744082204-001/init-core-data-001/]
   [junit4]   2> 9312 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=26, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 14441 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 14441 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 14477 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 14477 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 14479 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=28, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=10.85546875, 
floorSegmentMB=1.27734375, forceMergeDeletesPctAllowed=15.417164278324558, 
segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.18809989947688957
   [junit4]   2> 14619 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@545ec0ee[collection1] main]
   [junit4]   2> 14926 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 15270 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 15633 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 15889 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 15895 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 15896 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 15896 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 15899 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 15905 INFO  (coreLoadExecutor-6-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1551714547658653696
   [junit4]   2> 15911 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 15916 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 15919 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 15926 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 15927 INFO  
(searcherExecutor-7-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@545ec0ee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 15934 INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 15969 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeMixedAdds
   [junit4]   2> 15972 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib,
 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 16132 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 16184 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 16345 WARN  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is 
text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 16350 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 16392 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using 
configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1
   [junit4]   2> 16394 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_E291643744082204-001/init-core-data-001/]
   [junit4]   2> 16423 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 16424 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 16427 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5bbe45d8[collection1] main]
   [junit4]   2> 16428 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 16430 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 16449 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  
10000
   [junit4]   2> 16450 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 16451 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or 
recent updates, using new clock 1551714548231176192
   [junit4]   2> 16452 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 16452 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 16452 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 16453 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 16455 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 16456 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 16458 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 16459 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 16459 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5bbe45d8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 16465 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 16468 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to 
be used.
   [junit4]   2> 16468 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@745bc3ef[collection1] main]
   [junit4]   2> 16471 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@53bd364b
   [junit4]   2> 16488 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@745bc3ef[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 17643 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[529 (1551714548913799168)]} 0 1161
   [junit4]   2> 18137 INFO  (commitScheduler-15-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 18694 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending 
testSoftAndHardCommitMaxTimeMixedAdds
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SoftAutoCommitTest 
-Dtests.method=testSoftAndHardCommitMaxTimeMixedAdds 
-Dtests.seed=E291643744082204 -Dtests.slow=true -Dtests.locale=fr-CA 
-Dtests.timezone=Europe/Zagreb -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 2.79s J0 | 
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: soft529 wasn't fast 
enough
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E291643744082204:B3459DB7F57B12A3]:0)
   [junit4]    >        at 
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:111)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 18730 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting 
testSoftAndHardCommitMaxTimeDelete
   [junit4]   2> 18731 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to 
classloader, from paths: 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib,
 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 18835 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 18845 INFO  (commitScheduler-14-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 18903 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 19151 WARN  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.IndexSchema [collection1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 19154 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid 
field id
   [junit4]   2> 19159 INFO  (commitScheduler-15-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@69f9bd6b[collection1] main]
   [junit4]   2> 19163 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@69f9bd6b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 19164 INFO  (commitScheduler-14-thread-1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@fcc658d
   [junit4]   2> 19164 INFO  (commitScheduler-15-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 19182 INFO  (commitScheduler-14-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5ff15167[collection1] main]
   [junit4]   2> 19183 INFO  (commitScheduler-14-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 19183 INFO  
(searcherExecutor-12-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ff15167[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 19187 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' 
using configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1
   [junit4]   2> 19188 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_E291643744082204-001/init-core-data-001/]
   [junit4]   2> 19223 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 19223 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 19224 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@231a7722[collection1] main]
   [junit4]   2> 19225 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 19229 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 19247 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  
10000
   [junit4]   2> 19247 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 19251 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 19251 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 19251 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 19253 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 19258 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 19269 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 19276 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 19287 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 19288 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@231a7722[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 19312 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=37.107421875, 
floorSegmentMB=1.48046875, forceMergeDeletesPctAllowed=10.072061210110576, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 19317 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to 
be used.
   [junit4]   2> 19321 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@369aec29[collection1] main]
   [junit4]   2> 19321 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3038ce73
   [junit4]   2> 19321 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@369aec29[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 19330 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[529 (1551714551245832192)]} 0 4
   [junit4]   2> 19331 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 19332 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@55a0348e
   [junit4]   2> 19413 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@22342fbf[collection1] main]
   [junit4]   2> 19419 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@22342fbf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):c1)))}
   [junit4]   2> 19421 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 19421 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{commit=} 0 89
   [junit4]   2> 19426 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{delete=[529 (-1551714551347544064)]} 0 2
   [junit4]   2> 19928 INFO  (commitScheduler-19-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 19934 INFO  (commitScheduler-19-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@47315ae6[collection1] main]
   [junit4]   2> 19934 INFO  (commitScheduler-19-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 19935 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@47315ae6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 19942 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[550 (1551714551886512128)]} 0 5
   [junit4]   2> 20447 INFO  (commitScheduler-19-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 20478 INFO  (commitScheduler-19-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4e0630e8[collection1] main]
   [junit4]   2> 20478 INFO  (commitScheduler-19-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 20479 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4e0630e8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):c1)))}
   [junit4]   2> 20632 INFO  (commitScheduler-18-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 20633 INFO  (commitScheduler-18-thread-1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@55a0348e
   [junit4]   2> 20636 INFO  (commitScheduler-18-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@743acead[collection1] main]
   [junit4]   2> 20638 INFO  (commitScheduler-18-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 20638 INFO  
(searcherExecutor-16-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@743acead[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):c1)))}
   [junit4]   2> 22638 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[E291643744082204])
 [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending 
testSoftAndHardCommitMaxTimeDelete
   [junit4]   2> 22643 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting 
testSoftAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 22644 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to 
classloader, from paths: 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib,
 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 22732 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 22799 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 22992 WARN  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.IndexSchema [collection1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 22998 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid 
field id
   [junit4]   2> 23034 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' 
using configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1
   [junit4]   2> 23036 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_E291643744082204-001/init-core-data-001/]
   [junit4]   2> 23067 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 23067 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 23068 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@58bdf76c[collection1] main]
   [junit4]   2> 23077 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 23091 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 23115 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  
10000
   [junit4]   2> 23116 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 23125 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 23125 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 23125 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 23135 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 23136 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 23148 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 23150 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=97.525390625, 
floorSegmentMB=1.5888671875, forceMergeDeletesPctAllowed=4.749850345915162, 
segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5251568337719085
   [junit4]   2> 23155 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 23161 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 23162 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@58bdf76c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):c1)))}
   [junit4]   2> 23164 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to 
be used.
   [junit4]   2> 23169 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@33a6c7d[collection1] main]
   [junit4]   2> 23170 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@13a52698
   [junit4]   2> 23170 INFO  
(searcherExecutor-20-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@33a6c7d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):c1)))}
   [junit4]   2> 23180 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[5000 (1551714555280752640)]} 0 6
   [junit4]   2> 23182 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[5001 (1551714555288092672)]} 0 1
   [junit4]   2> 23184 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[5002 (1551714555290189824)]} 0 1
   [junit4]   2> 23186 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[5003 (1551714555292286976)]} 0 1
   [junit4]   2> 23189 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  
webapp=null path=null params={}{add=[5004 (1551714555295432704)]} 0 1
   [junit4]   2> 23190 INFO  
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[E291643744082204])
 [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending 
testSoftAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 23191 INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 23191 INFO  
(SUITE-SoftAutoCommitTest-seed#[E291643744082204]-worker) [    x:collection1] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1511279125
   [junit4]   2> 23194 INFO  
(coreCloseExecutor-24-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6f68a292
   [junit4]   2> 23202 INFO  
(coreCloseExecutor-24-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 23204 INFO  
(coreCloseExecutor-24-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@76b1877c
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_E291643744082204-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=RandomSimilarity(queryNorm=true): {}, locale=fr-CA, timezone=Europe/Zagreb
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 
(64-bit)/cpus=3,threads=1,free=21256288,total=54853632
   [junit4]   2> NOTE: All tests run in this JVM: [SoftAutoCommitTest]
   [junit4] Completed [3/653 (1!)] on J0 in 26.48s, 3 tests, 1 failure <<< 
FAILURES!

[...truncated 64451 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to