Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/983/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED:
org.apache.solr.update.SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
Error Message:
softCommit: did not find a single commit
Stack Trace:
java.lang.AssertionError: softCommit: did not find a single commit
at
__randomizedtesting.SeedInfo.seed([1096B271C6B5680B:76DBBAE33F92DFA3]: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.assertRapidMultiCommitQueues(SoftAutoCommitTest.java:495)
at
org.apache.solr.update.SoftAutoCommitTest.doTestSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:460)
at
org.apache.solr.update.SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:409)
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 12572 lines...]
[junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
[junit4] 2> 31504 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_1096B271C6B5680B-001/init-core-data-001
[junit4] 2> 31506 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 31516 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
w/ MAC_OS_X supressed clientAuth
[junit4] 2> 31758 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 31759 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib,
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 31863 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 31969 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 32933 WARN
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.LowerCaseTokenizerFactory]. Please consult documentation how to replace
it accordingly.
[junit4] 2> 33014 WARN
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-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> 33182 WARN
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-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> 33202 WARN
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-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> 33234 WARN
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-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> 33491 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 33942 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 33942 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 33971 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55cd31dc
[junit4] 2> 33971 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55cd31dc
[junit4] 2> 33971 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55cd31dc
[junit4] 2> 33974 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib,
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 34033 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 34064 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 34198 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 34238 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 34239 INFO (coreLoadExecutor-24-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@55cd31dc
[junit4] 2> 34239 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_1096B271C6B5680B-001/init-core-data-001/]
[junit4] 2> 34242 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 34325 WARN (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.OldLuceneQParserPlugin]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 34774 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 34774 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 34803 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 34803 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 34816 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=5, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=95.525390625,
floorSegmentMB=1.16015625, forceMergeDeletesPctAllowed=21.199737813824367,
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8251855762839388, deletesPctAllowed=23.033018895126602
[junit4] 2> 34819 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@39494[collection1] main]
[junit4] 2> 34823 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 35050 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 35309 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 35340 INFO (coreLoadExecutor-24-thread-1) [ x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1620378270494097408
[junit4] 2> 35345 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 35350 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 35354 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 35354 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 35354 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 35356 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testSoftCommitWithinAndHardCommitMaxTimeDelete
[junit4] 2> 35356 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 35357 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 35358 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 35369 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 35381 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 35386 INFO
(searcherExecutor-25-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@39494[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 40489 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1620378275764240384)]} 0 124
[junit4] 2> 40510 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1620378275915235328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 40510 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee
commitCommandVersion:1620378275915235328
[junit4] 2> 41009 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@105ebff0[collection1] main]
[junit4] 2> 41009 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 41026 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@105ebff0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313144900}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 41027 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{commit=} 0 516
[junit4] 2> 41045 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{delete=[529 (-1620378276458397696)]} 0 17
[junit4] 2> 41531 INFO (commitScheduler-29-thread-2) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 41562 INFO (commitScheduler-29-thread-2) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@675725b4[collection1] main]
[junit4] 2> 41566 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[550 (1620378277020434432)]} 0 2
[junit4] 2> 41564 INFO (commitScheduler-29-thread-2) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 41570 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@675725b4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 42068 INFO (commitScheduler-29-thread-2) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 42165 INFO (commitScheduler-29-thread-2) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@7e924b1a[collection1] main]
[junit4] 2> 42165 INFO (commitScheduler-29-thread-2) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42171 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@7e924b1a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 42231 INFO (commitScheduler-28-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42231 INFO (commitScheduler-28-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 42232 INFO (commitScheduler-28-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@600c05a0[collection1] realtime]
[junit4] 2> 42233 INFO (commitScheduler-28-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 48245 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testSoftCommitWithinAndHardCommitMaxTimeDelete
[junit4] 2> 48260 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> 53281 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5000 (1620378289294016512)]} 0 12
[junit4] 2> 53284 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5001 (1620378289308696576)]} 0 0
[junit4] 2> 53285 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5002 (1620378289310793728)]} 0 0
[junit4] 2> 53287 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5003 (1620378289312890880)]} 0 0
[junit4] 2> 53289 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5004 (1620378289313939456)]} 0 0
[junit4] 2> 53290 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 265220493078844ns -
265220471612343ns == 21ms
[junit4] 2> 53290 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1
[junit4] 2> 53290 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1
[junit4] 2> 53781 INFO (commitScheduler-29-thread-3) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 54346 INFO (commitScheduler-29-thread-3) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@382b5f30[collection1] main]
[junit4] 2> 54347 INFO (commitScheduler-29-thread-3) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 54353 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@382b5f30[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_2(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313157929}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 54481 INFO (commitScheduler-28-thread-2) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 54482 INFO (commitScheduler-28-thread-2) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 54486 INFO (commitScheduler-28-thread-2) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@233e038e[collection1] realtime]
[junit4] 2> 54487 INFO (commitScheduler-28-thread-2) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 60460 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> 60464 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testSoftCommitWithinAndHardCommitMaxTimeMixedAdds
[junit4] 2> 68894 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1620378305673822208)]} 0 4
[junit4] 2> 69394 INFO (commitScheduler-29-thread-4) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 69405 INFO (commitScheduler-29-thread-4) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3342397b[collection1] main]
[junit4] 2> 69406 INFO (commitScheduler-29-thread-4) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 69412 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3342397b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_2(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313157929}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_3(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313173491}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 70094 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 70094 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 70104 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[530 (1620378306941550592)]} 0 5
[junit4] 2> 70397 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4336e33[collection1] realtime]
[junit4] 2> 70401 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 70604 INFO (commitScheduler-29-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 70611 INFO (commitScheduler-29-thread-5) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@33348520[collection1] main]
[junit4] 2> 70623 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@33348520[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_2(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313157929}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_3(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313173491}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_4(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313174384}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 70629 INFO (commitScheduler-29-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 71306 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 71306 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 71348 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@69a418df[collection1] realtime]
[junit4] 2> 71349 INFO (commitScheduler-28-thread-3) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 72349 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testSoftCommitWithinAndHardCommitMaxTimeMixedAdds
[junit4] 2> 72369 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxDocs
[junit4] 2> 80386 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8000 (1620378317725106176)]} 0 3
[junit4] 2> 80388 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8001 (1620378317729300480)]} 0 0
[junit4] 2> 80390 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8002 (1620378317732446208)]} 0 0
[junit4] 2> 80393 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8003 (1620378317734543360)]} 0 0
[junit4] 2> 80396 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8004 (1620378317737689088)]} 0 2
[junit4] 2> 80403 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8005 (1620378317743980544)]} 0 1
[junit4] 2> 80404 INFO (commitScheduler-29-thread-6) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 80405 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8006 (1620378317747126272)]} 0 0
[junit4] 2> 80407 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{add=[8007 (1620378317749223424)]} 0 1
[junit4] 2> 80409 INFO (commitScheduler-28-thread-4) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 80430 INFO (commitScheduler-29-thread-6) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@784225b4[collection1] main]
[junit4] 2> 80431 INFO (commitScheduler-29-thread-6) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 80431 INFO (commitScheduler-28-thread-4) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 80438 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@784225b4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_2(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313157929}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_3(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313173491}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_4(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313174384}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 80440 INFO (commitScheduler-28-thread-4) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@26a3e3aa[collection1] realtime]
[junit4] 2> 80441 INFO (commitScheduler-28-thread-4) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 81438 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxDocs-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxDocs
[junit4] 2> 81444 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
[junit4] 2> 86452 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5000 (1620378324085768192)]} 0 3
[junit4] 2> 86454 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5001 (1620378324089962496)]} 0 0
[junit4] 2> 86455 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5002 (1620378324092059648)]} 0 0
[junit4] 2> 86457 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5003 (1620378324093108224)]} 0 0
[junit4] 2> 86462 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5004 (1620378324095205376)]} 0 4
[junit4] 2> 86463 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 265253666286642ns -
265253651349076ns == 14ms
[junit4] 2> 86463 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1
[junit4] 2> 86463 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1
[junit4] 2> 86952 INFO (commitScheduler-29-thread-7) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 87652 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 89466 INFO
(TEST-SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SoftAutoCommitTest
-Dtests.method=testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
-Dtests.seed=1096B271C6B5680B -Dtests.slow=true -Dtests.locale=fr-FR
-Dtests.timezone=Europe/Minsk -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 8.04s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds <<<
[junit4] > Throwable #1: java.lang.AssertionError: softCommit: did not
find a single commit
[junit4] > at
__randomizedtesting.SeedInfo.seed([1096B271C6B5680B:76DBBAE33F92DFA3]:0)
[junit4] > at
org.apache.solr.update.SoftAutoCommitTest.assertRapidMultiCommitQueues(SoftAutoCommitTest.java:495)
[junit4] > at
org.apache.solr.update.SoftAutoCommitTest.doTestSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:460)
[junit4] > at
org.apache.solr.update.SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:409)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 89488 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> 90015 INFO (commitScheduler-29-thread-7) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6a4b594c[collection1] main]
[junit4] 2> 90015 INFO (commitScheduler-29-thread-7) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 90016 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 90021 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1620378327827087360)]} 0 4
[junit4] 2> 90023 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6a4b594c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_3(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313173491}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_4(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313174384}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 90101 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@23f3cbd1[collection1] realtime]
[junit4] 2> 90103 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 90521 INFO (commitScheduler-29-thread-7) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 90522 INFO (commitScheduler-29-thread-7) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1da37248[collection1] main]
[junit4] 2> 90523 INFO (commitScheduler-29-thread-7) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 90531 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1da37248[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_4(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313174384}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_7(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313194136}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 91221 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 91222 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 91226 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[530 (1620378329091670016)]} 0 3
[junit4] 2> 91728 INFO (commitScheduler-29-thread-8) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 91887 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2e726766[collection1] realtime]
[junit4] 2> 91888 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 91896 INFO (commitScheduler-29-thread-8) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@57497ba[collection1] main]
[junit4] 2> 91899 INFO (commitScheduler-29-thread-8) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 91913 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@57497ba[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_7(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313194136}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_8(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313195896}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 92427 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 92428 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 92452 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@7f0b6264[collection1] realtime]
[junit4] 2> 92453 INFO (commitScheduler-28-thread-5) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 93455 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> 93462 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 98473 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1620378336689651712)]} 0 4
[junit4] 2> 98475 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1620378336695943168,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98475 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee
commitCommandVersion:1620378336695943168
[junit4] 2> 99070 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@66d89abe[collection1] main]
[junit4] 2> 99072 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 99079 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@66d89abe[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_8(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313195896}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_9(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313202828}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 99105 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{commit=} 0 630
[junit4] 2> 99109 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{delete=[529 (-1620378337358643200)]} 0 2
[junit4] 2> 99634 INFO (commitScheduler-29-thread-10) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 99662 INFO (commitScheduler-29-thread-10) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@5b4d5159[collection1] main]
[junit4] 2> 99669 INFO (commitScheduler-29-thread-10) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 99672 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5b4d5159[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):c1:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313146254}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_8(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313195896}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 99688 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[550 (1620378337944797184)]} 0 22
[junit4] 2> 100189 INFO (commitScheduler-29-thread-10) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 100309 INFO (commitScheduler-28-thread-6) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 100348 INFO (commitScheduler-29-thread-10) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4a6cd7d6[collection1] main]
[junit4] 2> 100349 INFO (commitScheduler-29-thread-10) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 100349 INFO (commitScheduler-28-thread-6) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 100356 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4a6cd7d6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_8(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313195896}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_a(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313204416}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 100363 INFO (commitScheduler-28-thread-6) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6d218d74[collection1] realtime]
[junit4] 2> 100368 INFO (commitScheduler-28-thread-6) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 106377 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 106383 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
[junit4] 2> 114801 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1620378353811849216)]} 0 3
[junit4] 2> 115301 INFO (commitScheduler-29-thread-11) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 115317 INFO (commitScheduler-29-thread-11) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6d799c57[collection1] main]
[junit4] 2> 115319 INFO (commitScheduler-29-thread-11) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 115325 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6d799c57[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_8(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313195896}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_a(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313204416}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_b(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313219404}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 116001 INFO (commitScheduler-28-thread-7) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 116001 INFO (commitScheduler-28-thread-7) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 116003 INFO (commitScheduler-28-thread-7) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@46d6d32e[collection1] realtime]
[junit4] 2> 116006 INFO (commitScheduler-28-thread-7) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 116008 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[530 (1620378355075383296)]} 0 5
[junit4] 2> 116508 INFO (commitScheduler-29-thread-12) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 117208 INFO (commitScheduler-28-thread-8) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 118104 INFO (commitScheduler-29-thread-12) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@34948899[collection1] main]
[junit4] 2> 118105 INFO (commitScheduler-29-thread-12) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 118113 INFO (commitScheduler-28-thread-8) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 118118 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@34948899[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_a(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313204416}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_b(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313219404}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_c(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313221576}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 118123 INFO (commitScheduler-28-thread-8) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@47ec34de[collection1] realtime]
[junit4] 2> 118127 INFO (commitScheduler-28-thread-8) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 119134 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
[junit4] 2> 119141 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testHardCommitWithinAndSoftCommitMaxTimeDelete
[junit4] 2> 124153 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1620378363614986240)]} 0 6
[junit4] 2> 124154 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1620378363622326272,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 124154 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee
commitCommandVersion:1620378363622326272
[junit4] 2> 124664 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4112d48b[collection1] main]
[junit4] 2> 124666 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 124675 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4112d48b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_a(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313204416}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_c(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313221576}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_d(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313228448}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 124677 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{commit=} 0 522
[junit4] 2> 124683 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{delete=[529 (-1620378364172828672)]} 0 4
[junit4] 2> 125183 INFO (commitScheduler-29-thread-14) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 125185 INFO (commitScheduler-29-thread-14) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@35784750[collection1] main]
[junit4] 2> 125187 INFO (commitScheduler-29-thread-14) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 125197 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@35784750[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_a(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313204416}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_c(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313221576}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 125201 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[550 (1620378364705505280)]} 0 13
[junit4] 2> 125701 INFO (commitScheduler-29-thread-15) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 125734 INFO (commitScheduler-29-thread-15) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ba99125[collection1] main]
[junit4] 2> 125735 INFO (commitScheduler-29-thread-15) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 125743 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1ba99125[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_6(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313193829}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_c(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313221576}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_e(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313229818}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 125882 INFO (commitScheduler-28-thread-9) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 125882 INFO (commitScheduler-28-thread-9) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 125884 INFO (commitScheduler-28-thread-9) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4572dfd5[collection1] realtime]
[junit4] 2> 125886 INFO (commitScheduler-28-thread-9) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 131905 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testHardCommitWithinAndSoftCommitMaxTimeDelete
[junit4] 2> 131913 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
[junit4] 2> 136925 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5000 (1620378377004253184)]} 0 10
[junit4] 2> 136927 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5001 (1620378377015787520)]} 0 0
[junit4] 2> 136930 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5002 (1620378377017884672)]} 0 0
[junit4] 2> 136932 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5003 (1620378377021030400)]} 0 0
[junit4] 2> 136937 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5004 (1620378377026273280)]} 0 2
[junit4] 2> 136939 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxTimeMillis: 265304141968109ns -
265304118123259ns == 23ms
[junit4] 2> 136939 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxExpectedSoft=1
[junit4] 2> 136939 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.u.SoftAutoCommitTest maxExpectedHard=1
[junit4] 2> 137424 INFO (commitScheduler-29-thread-16) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 137494 INFO (commitScheduler-29-thread-16) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2eead4db[collection1] main]
[junit4] 2> 137495 INFO (commitScheduler-29-thread-16) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 137501 INFO (searcherExecutor-25-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2eead4db[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.7.0):c8:[diagnostics={os=Mac
OS X, java.vendor=Oracle Corporation, java.version=1.8.0_172,
java.vm.version=25.172-b11, lucene.version=7.7.0, os.arch=x86_64,
java.runtime.version=1.8.0_172-b11, source=flush, os.version=10.11.6,
timestamp=1545313184518}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_c(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313221576}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_e(7.7.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313229818}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_f(7.7.0):c5:[diagnostics={os=Mac OS X, java.vendor=Oracle
Corporation, java.version=1.8.0_172, java.vm.version=25.172-b11,
lucene.version=7.7.0, os.arch=x86_64, java.runtime.version=1.8.0_172-b11,
source=flush, os.version=10.11.6,
timestamp=1545313241582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 138124 INFO (commitScheduler-28-thread-10) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 138124 INFO (commitScheduler-28-thread-10) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4dd19eee commitCommandVersion:0
[junit4] 2> 138126 INFO (commitScheduler-28-thread-10) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@69141b89[collection1] realtime]
[junit4] 2> 138127 INFO (commitScheduler-28-thread-10) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 143610 INFO
(TEST-SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds-seed#[1096B271C6B5680B])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
[junit4] 2> 143610 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 143611 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=487818785
[junit4] 2> 143612 INFO (coreCloseExecutor-30-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@478cd43b
[junit4] 2> 143612 INFO (coreCloseExecutor-30-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=478cd43b
[junit4] 2> 143612 INFO (coreCloseExecutor-30-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7a2e6344: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@463831a1
[junit4] 2> 143655 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 143656 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@75d8d8ff: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@38dcd6da
[junit4] 2> 143668 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 143668 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@61a88dab: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@4d842925
[junit4] 2> 143668 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 143668 INFO
(SUITE-SoftAutoCommitTest-seed#[1096B271C6B5680B]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2072f068: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@3ac90f68
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.SoftAutoCommitTest_1096B271C6B5680B-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{multiDefault=Lucene50(blocksize=128), subject=Lucene50(blocksize=128),
id=PostingsFormat(name=MockRandom), text=FST50},
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70),
_version_=DocValuesFormat(name=Lucene70),
intDefault=DocValuesFormat(name=Lucene70),
id_i1=DocValuesFormat(name=Lucene70),
range_facet_i_dv=DocValuesFormat(name=Asserting),
intDvoDefault=DocValuesFormat(name=Lucene70),
range_facet_l=DocValuesFormat(name=Asserting),
timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=818,
maxMBSortInHeap=6.426730289168487, sim=RandomSimilarity(queryNorm=false): {},
locale=fr-FR, timezone=Europe/Minsk
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_172
(64-bit)/cpus=3,threads=1,free=144633328,total=198180864
[junit4] 2> NOTE: All tests run in this JVM:
[TestSlowCompositeReaderWrapper, ClassificationUpdateProcessorIntegrationTest,
CoreAdminOperationTest, TestFieldCacheSort, SoftAutoCommitTest]
[junit4] Completed [19/836 (1!)] on J0 in 112.44s, 10 tests, 1 failure <<<
FAILURES!
[...truncated 52079 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 2458 links (2009 relative) to 3221 anchors in 247 files
[echo] Validated Links & Anchors via:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-ref-guide/bare-bones-html/
-documentation-lint:
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/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 =
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/top-level-ivy
[...truncated too long message...]
4] OK 9.08s J0 | SoftAutoCommitTest.testSoftAndHardCommitMaxDocs
[junit4] OK 11.8s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
[junit4] OK 11.8s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds
[junit4] OK 12.8s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete
[junit4] OK 11.9s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
[junit4] OK 12.8s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete
[junit4] OK 11.7s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
[junit4] Completed [2/5] on J0 in 148.94s, 10 tests
[junit4]
[junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
[junit4] OK 12.3s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete
[junit4] OK 11.6s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds
[junit4] OK 11.8s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds
[junit4] OK 9.08s J0 | SoftAutoCommitTest.testSoftAndHardCommitMaxDocs
[junit4] OK 11.7s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
[junit4] OK 11.9s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds
[junit4] OK 12.4s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete
[junit4] OK 11.9s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
[junit4] OK 12.3s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete
[junit4] OK 11.6s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
[junit4] Completed [3/5] on J0 in 118.48s, 10 tests
[junit4]
[junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
[junit4] OK 12.9s J1 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete
[junit4] OK 11.7s J1 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds
[junit4] OK 11.8s J1 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds
[junit4] OK 9.07s J1 | SoftAutoCommitTest.testSoftAndHardCommitMaxDocs
[junit4] OK 11.7s J1 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
[junit4] OK 11.9s J1 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds
[junit4] OK 12.7s J1 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete
[junit4] OK 11.9s J1 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
[junit4] OK 12.4s J1 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete
[junit4] OK 11.6s J1 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
[junit4] Completed [4/5] on J1 in 119.22s, 10 tests
[junit4]
[junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
[junit4] OK 12.3s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeDelete
[junit4] OK 11.6s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds
[junit4] OK 11.9s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeMixedAdds
[junit4] OK 9.05s J0 | SoftAutoCommitTest.testSoftAndHardCommitMaxDocs
[junit4] OK 11.6s J0 |
SoftAutoCommitTest.testSoftCommitWithinAndHardCommitMaxTimeRapidAdds
[junit4] OK 11.9s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds
[junit4] OK 12.3s J0 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete
[junit4] OK 11.9s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeMixedAdds
[junit4] OK 12.3s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeDelete
[junit4] OK 11.6s J0 |
SoftAutoCommitTest.testHardCommitWithinAndSoftCommitMaxTimeRapidAdds
[junit4] Completed [5/5] on J0 in 117.30s, 10 tests
[junit4]
[junit4] JVM J0: 0.97 .. 387.13 = 386.16s
[junit4] JVM J1: 0.96 .. 271.08 = 270.11s
[junit4] Execution time total: 6 minutes 27 seconds
[junit4] Tests summary: 5 suites, 50 tests
[junit4] Could not remove temporary path:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1
(java.nio.file.DirectoryNotEmptyException: Remaining files:
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
(java.nio.file.DirectoryNotEmptyException: Remaining files:
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 160.16s | org.apache.solr.search.facet.TestJsonFacets
[junit4:tophints] 137.24s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 127.54s | org.apache.solr.update.SoftAutoCommitTest
[junit4:tophints] 94.89s | org.apache.solr.cloud.BasicDistributedZkTest
[junit4:tophints] 84.52s | org.apache.solr.cloud.CollectionsAPISolrJTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 6 minutes 29 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.update.SoftAutoCommitTest
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to MacOSX VBOX
at
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
at
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:957)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
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:1835)
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:3088)
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:1149)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
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:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
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:1835)
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/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=28eaf354fb46ba3d5593551c690f4ae17b89ce23,
workspace=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX
[WARNINGS] Computing warning deltas based on reference build #981
Recording test results
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
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)
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
