Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/464/
1 tests failed.
FAILED:
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds
Error Message:
1: hard occurred too fast: 1053 < (1200 * 1)
Stack Trace:
java.lang.AssertionError: 1: hard occurred too fast: 1053 < (1200 * 1)
at
__randomizedtesting.SeedInfo.seed([6AEA7D976DA6D6C7:36FFD3AE862497BF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:344)
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:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11814 lines...]
[junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_6AEA7D976DA6D6C7-001/init-core-data-001
[junit4] 2> 2184890 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2184893 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2184893 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2184971 WARN
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 2184972 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 2185036 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2185712 WARN
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 2185723 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2185752 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 2185793 INFO (coreLoadExecutor-4793-thread-1) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2185926 WARN (coreLoadExecutor-4793-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 2185928 INFO (coreLoadExecutor-4793-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 2185998 INFO (coreLoadExecutor-4793-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2186497 WARN (coreLoadExecutor-4793-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2186499 INFO (coreLoadExecutor-4793-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2186515 INFO (coreLoadExecutor-4793-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 2186515 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_6AEA7D976DA6D6C7-001/init-core-data-001/]
[junit4] 2> 2186516 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7282c9a3
[junit4] 2> 2186516 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=184253597,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 2186531 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2186531 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2186532 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2186532 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2186532 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=43, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 2186533 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@5543cca6[collection1] main]
[junit4] 2> 2186533 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2186542 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2186584 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2186585 INFO (coreLoadExecutor-4793-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1547281149706895360
[junit4] 2> 2186588 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2186590 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> 2186591 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from
paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2186592 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2186596 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2186596 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2186597 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2186597 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2186600 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2186601 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2186601 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2186601 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2186602 INFO
(searcherExecutor-4794-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5543cca6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2186658 WARN
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated,
use <mergePolicyFactory> instead.
[junit4] 2> 2186670 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 2186711 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2186955 WARN
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.s.IndexSchema [collection1] default search field in schema is
text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2186965 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2186972 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using
configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 2186986 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_6AEA7D976DA6D6C7-001/init-core-data-001/]
[junit4] 2> 2186986 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding
Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7282c9a3
[junit4] 2> 2187061 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2187061 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2187061 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@2f3ec995[collection1] main]
[junit4] 2> 2187062 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2187062 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2187065 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for
10000
[junit4] 2> 2187065 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2187066 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2187066 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2187066 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2187066 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2187066 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or
recent updates, using new clock 1547281150211260416
[junit4] 2> 2187066 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2187066 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2187067 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2187067 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2187068 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2f3ec995[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2187068 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.16891177358649623]
[junit4] 2> 2187077 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to
be used.
[junit4] 2> 2187077 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@4f18a175[collection1] main]
[junit4] 2> 2187077 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@6a373399
[junit4] 2> 2187089 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4f18a175[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2187432 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[5000 (1547281150230134784)]} 0 348
[junit4] 2> 2187434 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[5001 (1547281150597136384)]} 0 0
[junit4] 2> 2187435 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[5002 (1547281150598184960)]} 0 0
[junit4] 2> 2187435 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[5003 (1547281150598184961)]} 0 0
[junit4] 2> 2187436 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[5004 (1547281150598184962)]} 0 0
[junit4] 2> 2187929 INFO (commitScheduler-4802-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 2187960 INFO (commitScheduler-4802-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@5a80b478[collection1] main]
[junit4] 2> 2187961 INFO (commitScheduler-4802-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2187965 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5a80b478[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)))}
[junit4] 2> 2188289 INFO (commitScheduler-4801-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2188289 INFO (commitScheduler-4801-thread-1) [
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@920c75d
[junit4] 2> 2188290 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending
testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SoftAutoCommitTest
-Dtests.method=testSoftAndHardCommitMaxTimeRapidAdds
-Dtests.seed=6AEA7D976DA6D6C7 -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=es-PE -Dtests.timezone=Etc/GMT-2 -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 1.70s J1 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds <<<
[junit4] > Throwable #1: java.lang.AssertionError: 1: hard occurred too
fast: 1053 < (1200 * 1)
[junit4] > at
__randomizedtesting.SeedInfo.seed([6AEA7D976DA6D6C7:36FFD3AE862497BF]:0)
[junit4] > at
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:344)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2188292 INFO (commitScheduler-4801-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@17773dff[collection1] main]
[junit4] 2> 2188293 INFO (commitScheduler-4801-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2188294 INFO
(searcherExecutor-4799-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@17773dff[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)))}
[junit4] 2> 2188301 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting
testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> 2188317 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to
classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2188494 WARN
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is
deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2188510 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 2189072 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2189752 WARN
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.IndexSchema [collection1] default search field in
schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2189755 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid
field id
[junit4] 2> 2189774 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1'
using configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 2189781 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_6AEA7D976DA6D6C7-001/init-core-data-001/]
[junit4] 2> 2189781 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding
Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7282c9a3
[junit4] 2> 2189844 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2189845 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2189845 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@56e86cea[collection1] main]
[junit4] 2> 2189846 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2189850 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2189859 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for
10000
[junit4] 2> 2189859 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2189860 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2189860 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2189860 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2189864 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2189865 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2189865 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2189866 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2189867 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2189867 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@56e86cea[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)))}
[junit4] 2> 2189881 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=19, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 2189885 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to
be used.
[junit4] 2> 2189886 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@60c1df42[collection1] main]
[junit4] 2> 2189886 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@55c37778
[junit4] 2> 2189889 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@60c1df42[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)))}
[junit4] 2> 2189952 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[529 (1547281153197604864)]} 0 38
[junit4] 2> 2190441 INFO (commitScheduler-4806-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 2190444 INFO (commitScheduler-4806-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@2429ddb4[collection1] main]
[junit4] 2> 2190457 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2429ddb4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_1(6.3.0):c1)))}
[junit4] 2> 2190458 INFO (commitScheduler-4806-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2190473 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[530 (1547281153783758848)]} 0 0
[junit4] 2> 2190976 INFO (commitScheduler-4806-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 2191017 INFO (commitScheduler-4806-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@36e1d474[collection1] main]
[junit4] 2> 2191018 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@36e1d474[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_1(6.3.0):c1) Uninverting(_2(6.3.0):c1)))}
[junit4] 2> 2191018 INFO (commitScheduler-4806-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2191137 INFO (commitScheduler-4805-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2191137 INFO (commitScheduler-4805-thread-1) [
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@54715ca4
[junit4] 2> 2191139 INFO (commitScheduler-4805-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@6d3801d9[collection1] main]
[junit4] 2> 2191139 INFO (commitScheduler-4805-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2191140 INFO
(searcherExecutor-4803-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6d3801d9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_1(6.3.0):c1) Uninverting(_2(6.3.0):c1)))}
[junit4] 2> 2194348 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending
testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> 2194351 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting
testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 2194351 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to
classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2194438 WARN
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is
deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2194440 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 2194592 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2195013 WARN
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.IndexSchema [collection1] default search field in
schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 2195016 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid
field id
[junit4] 2> 2195066 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1'
using configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 2195066 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_6AEA7D976DA6D6C7-001/init-core-data-001/]
[junit4] 2> 2195066 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding
Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7282c9a3
[junit4] 2> 2195144 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2195145 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2195145 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@3e4506a4[collection1] main]
[junit4] 2> 2195145 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2195165 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2195173 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for
10000
[junit4] 2> 2195173 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2195174 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2195174 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2195174 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 2195174 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2195174 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2195174 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2195175 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2195176 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2195177 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3e4506a4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_1(6.3.0):c1) Uninverting(_2(6.3.0):c1)))}
[junit4] 2> 2195201 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 2195209 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to
be used.
[junit4] 2> 2195217 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@6072311e[collection1] main]
[junit4] 2> 2195217 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@72925374
[junit4] 2> 2195221 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6072311e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_1(6.3.0):c1) Uninverting(_2(6.3.0):c1)))}
[junit4] 2> 2195265 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[529 (1547281158793854976)]} 0 14
[junit4] 2> 2195266 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2195266 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@2b64f827
[junit4] 2> 2195275 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@f578949[collection1] main]
[junit4] 2> 2195304 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@f578949[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_2(6.3.0):c1) Uninverting(_3(6.3.0):c1)))}
[junit4] 2> 2195304 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2195304 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{commit=} 0 38
[junit4] 2> 2195305 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{delete=[529 (-1547281158850478080)]} 0 0
[junit4] 2> 2195806 INFO (commitScheduler-4810-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 2195817 INFO (commitScheduler-4810-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@292af172[collection1] main]
[junit4] 2> 2195818 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@292af172[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_2(6.3.0):c1)))}
[junit4] 2> 2195818 INFO (commitScheduler-4810-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2195825 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
webapp=null path=null params={}{add=[550 (1547281159389446144)]} 0 6
[junit4] 2> 2196325 INFO (commitScheduler-4810-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 2196328 INFO (commitScheduler-4810-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@9306fa1[collection1]
main]
[junit4] 2> 2196328 INFO (commitScheduler-4810-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2196329 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@9306fa1[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_2(6.3.0):c1) Uninverting(_4(6.3.0):c1)))}
[junit4] 2> 2196507 INFO (commitScheduler-4809-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2196507 INFO (commitScheduler-4809-thread-1) [
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@2b64f827
[junit4] 2> 2196508 INFO (commitScheduler-4809-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@78436ea7[collection1] main]
[junit4] 2> 2196508 INFO (commitScheduler-4809-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2196509 INFO
(searcherExecutor-4807-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@78436ea7[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c5)
Uninverting(_2(6.3.0):c1) Uninverting(_4(6.3.0):c1)))}
[junit4] 2> 2198508 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[6AEA7D976DA6D6C7])
[ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending
testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 2198508 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ x:collection1]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2198509 INFO
(SUITE-SoftAutoCommitTest-seed#[6AEA7D976DA6D6C7]-worker) [ x:collection1]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=721286091
[junit4] 2> 2198529 INFO
(coreCloseExecutor-4811-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@13e1648a
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_6AEA7D976DA6D6C7-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{range_facet_l_dv=PostingsFormat(name=Memory doPackFST= false),
_version_=BlockTreeOrds(blocksize=128), multiDefault=Lucene50(blocksize=128),
subject=Lucene50(blocksize=128), intDefault=BlockTreeOrds(blocksize=128),
id=PostingsFormat(name=Memory doPackFST= false),
range_facet_i_dv=Lucene50(blocksize=128),
text=PostingsFormat(name=LuceneVarGapFixedInterval),
range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54),
range_facet_i_dv=DocValuesFormat(name=Memory),
timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1064,
maxMBSortInHeap=5.944620409194303,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=es-PE,
timezone=Etc/GMT-2
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_102 (64-bit)/cpus=4,threads=1,free=358189336,total=532676608
[junit4] 2> NOTE: All tests run in this JVM: [DirectSolrSpellCheckerTest,
TestJsonRequest, TestCursorMarkWithoutUniqueKey,
TestTolerantUpdateProcessorRandomCloud, BlockJoinFacetDistribTest,
SolrIndexSplitterTest, TestNumericTerms64,
BigEndianAscendingWordSerializerTest, TestBackupRepositoryFactory,
TestCSVResponseWriter, TestRawResponseWriter, DocValuesTest,
TestCustomDocTransformer, TestImplicitCoreProperties, DeleteStatusTest,
HdfsRestartWhileUpdatingTest, SimpleCollectionCreateDeleteTest, JsonLoaderTest,
TestStressVersions, TestSystemIdResolver, TestNRTOpen, ConvertedLegacyTest,
TestSolrCoreSnapshots, TestPerFieldSimilarityClassic,
CollectionsAPIDistributedZkTest, SampleTest, ZkCLITest, TestHashQParserPlugin,
TestReqParamsAPI, TestLeaderElectionWithEmptyReplica, URLClassifyProcessorTest,
TestSchemaNameResource, TestDistributedStatsComponentCardinality,
ChangedSchemaMergeTest, CoreAdminRequestStatusTest,
TestSimpleTrackingShardHandler, CachingDirectoryFactoryTest, IndexSchemaTest,
TestFieldSortValues, TestOnReconnectListenerSupport, SolrXmlInZkTest,
SolrInfoMBeanTest, EnumFieldTest, HdfsNNFailoverTest,
IndexSchemaRuntimeFieldTest, SolrIndexConfigTest, TestManagedResourceStorage,
TestSolrConfigHandler, TestSortingResponseWriter,
TestDynamicFieldCollectionResource, DistributedQueryComponentOptimizationTest,
DistributedMLTComponentTest, CollectionReloadTest, SearchHandlerTest,
TestRebalanceLeaders, DateMathParserTest, TestExactStatsCache, TestQueryTypes,
SortByFunctionTest, DistanceUnitsTest, DirectSolrConnectionTest,
TestXmlQParserPlugin, SimplePostToolTest, TestManagedSynonymFilterFactory,
PKIAuthenticationIntegrationTest, CSVRequestHandlerTest,
IndexBasedSpellCheckerTest, HdfsTlogReplayBufferedWhileIndexingTest,
EchoParamsTest, SpatialFilterTest, HdfsDirectoryTest,
TestCollapseQParserPlugin, TestSweetSpotSimilarityFactory, ZkSolrClientTest,
TestTrie, TestSlowCompositeReaderWrapper, AnalysisErrorHandlingTest,
CdcrReplicationHandlerTest, LoggingHandlerTest, TestDownShardTolerantSearch,
TestClassicSimilarityFactory, TestOrdValues, TestUseDocValuesAsStored2,
DistribJoinFromCollectionTest, TestSolrIndexConfig,
ClassificationUpdateProcessorFactoryTest, CopyFieldTest, SortSpecParsingTest,
DistributedFacetPivotLargeTest, TestTrackingShardHandlerFactory,
TestCharFilters, TestLRUCache, SecurityConfHandlerTest, RulesTest,
DocumentBuilderTest, TestFieldResource, TestSchemaVersionResource,
SpellingQueryConverterTest, CollectionsAPIAsyncDistributedZkTest,
TestSuggestSpellingConverter, HdfsChaosMonkeyNothingIsSafeTest,
OpenCloseCoreStressTest, ForceLeaderTest, ShardRoutingCustomTest,
ChaosMonkeySafeLeaderTest, SyncSliceTest, LeaderElectionIntegrationTest,
ShardRoutingTest, TestReplicationHandler, LeaderElectionTest,
TestRandomDVFaceting, TestHashPartitioner, ZkControllerTest,
DistributedTermsComponentTest, StatsComponentTest, BasicFunctionalityTest,
TestLazyCores, HighlighterTest, SoftAutoCommitTest]
[junit4] Completed [354/637 (1!)] on J1 in 13.76s, 3 tests, 1 failure <<<
FAILURES!
[...truncated 41872 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: /tmp/ecj1183209237
[ecj-lint] Compiling 232 source files to /tmp/ecj1183209237
[ecj-lint] ----------
[ecj-lint] 1. ERROR in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/queryparser/src/java/org/apache/lucene/queryparser/classic/MultiFieldQueryParser.java
(at line 30)
[ecj-lint] import org.apache.lucene.search.TermQuery;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.search.TermQuery is never used
[ecj-lint] ----------
[ecj-lint] 1 problem (1 error)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:763: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:101: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/build.xml:204:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:2177:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1992:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:2031:
Compile failed; see the compiler error output for details.
Total time: 135 minutes 7 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
