Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/2938/
Java: 64bit/jdk1.8.0_121 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED:
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds
Error Message:
2: hard wasn't fast enough
Stack Trace:
java.lang.AssertionError: 2: hard wasn't fast enough
at
__randomizedtesting.SeedInfo.seed([8DF144F35FB6D666:D1E4EACAB434971E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:382)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11897 lines...]
[junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_8DF144F35FB6D666-001/init-core-data-001
[junit4] 2> 835086 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 835089 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-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> 835090 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 835090 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 835127 WARN
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 835130 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 835162 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 835263 WARN
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 835265 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 835271 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 835286 WARN
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 835288 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@71c1b7d6
[junit4] 2> 835288 WARN
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 835291 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@71c1b7d6
[junit4] 2> 835291 WARN
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 835291 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@71c1b7d6
[junit4] 2> 835298 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 835331 WARN (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is
deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 835333 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 835367 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 835489 WARN (coreLoadExecutor-3384-thread-1) [
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> 835493 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 835500 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 835500 WARN (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was
configured, using first MBeanServer.
[junit4] 2> 835500 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@71c1b7d6
[junit4] 2> 835500 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_8DF144F35FB6D666-001/init-core-data-001/]
[junit4] 2> 835501 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@71c1b7d6
[junit4] 2> 835502 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=67.3505859375,
floorSegmentMB=1.134765625, forceMergeDeletesPctAllowed=0.015081240040699662,
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.3913219989212172
[junit4] 2> 835551 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 835551 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 835555 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 835555 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 835555 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=50, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=79.603515625,
floorSegmentMB=0.990234375, forceMergeDeletesPctAllowed=13.343196556862335,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 835556 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@14cbaefe[collection1] main]
[junit4] 2> 835556 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 835567 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 835667 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 835667 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 835667 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 835668 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 835668 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 835668 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 835668 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 835668 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 835668 INFO (coreLoadExecutor-3384-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1560303334711099392
[junit4] 2> 835668 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 835668 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 835669 INFO
(searcherExecutor-3385-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@14cbaefe[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 835672 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 835674 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 840676 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1560303339961319424)]} 0 2
[junit4] 2> 840678 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 840678 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@25b23642
[junit4] 2> 840681 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e3505c4[collection1] main]
[junit4] 2> 840681 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 840682 INFO (searcherExecutor-3385-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6e3505c4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C1)))}
[junit4] 2> 840682 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{commit=} 0 4
[junit4] 2> 840683 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{delete=[529 (-1560303339969708032)]} 0 0
[junit4] 2> 841183 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 841184 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@37d4dca7[collection1] main]
[junit4] 2> 841184 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 841184 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[550 (1560303340495044608)]} 0 0
[junit4] 2> 841186 INFO (searcherExecutor-3385-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@37d4dca7[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 841685 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 841686 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@34bf868c[collection1] main]
[junit4] 2> 841686 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 841687 INFO (searcherExecutor-3385-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@34bf868c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.5.0):C1)))}
[junit4] 2> 841883 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841883 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@25b23642
[junit4] 2> 841884 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@115fe2fa[collection1] realtime]
[junit4] 2> 841884 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 847885 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[8DF144F35FB6D666])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 847886 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> 856719 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5000 (1560303352766529536)]} 0 3832
[junit4] 2> 856719 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5001 (1560303356784672768)]} 0 0
[junit4] 2> 856720 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5002 (1560303356785721344)]} 0 0
[junit4] 2> 856720 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5003 (1560303356785721345)]} 0 0
[junit4] 2> 856720 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[5004 (1560303356785721346)]} 0 0
[junit4] 2> 857219 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 857221 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@55089586[collection1] main]
[junit4] 2> 857221 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 857222 INFO (searcherExecutor-3385-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@55089586[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.5.0):C1)
Uninverting(_2(6.5.0):C5)))}
[junit4] 2> 857919 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 857919 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@25b23642
[junit4] 2> 857919 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6c6cd03a[collection1] realtime]
[junit4] 2> 857919 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 882923 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SoftAutoCommitTest
-Dtests.method=testSoftAndHardCommitMaxTimeRapidAdds
-Dtests.seed=8DF144F35FB6D666 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=en-PH -Dtests.timezone=US/Pacific -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 35.0s J1 |
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds <<<
[junit4] > Throwable #1: java.lang.AssertionError: 2: hard wasn't fast
enough
[junit4] > at
__randomizedtesting.SeedInfo.seed([8DF144F35FB6D666:D1E4EACAB434971E]:0)
[junit4] > at
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:382)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 882927 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> 891328 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[529 (1560303393073790976)]} 0 0
[junit4] 2> 891828 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 891829 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6aa31af0[collection1] main]
[junit4] 2> 891829 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 891830 INFO (searcherExecutor-3385-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6aa31af0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.5.0):C1)
Uninverting(_2(6.5.0):C5) Uninverting(_3(6.5.0):C1)))}
[junit4] 2> 892528 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 892528 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@25b23642
[junit4] 2> 892529 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4cc5c18[collection1] realtime]
[junit4] 2> 892529 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 892530 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{add=[530 (1560303394334179328)]} 0 0
[junit4] 2> 893030 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 893031 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@599a1e41[collection1] main]
[junit4] 2> 893031 INFO (commitScheduler-3389-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 893032 INFO (searcherExecutor-3385-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@599a1e41[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.5.0):C1)
Uninverting(_2(6.5.0):C5) Uninverting(_3(6.5.0):C1)
Uninverting(_4(6.5.0):C1)))}
[junit4] 2> 893730 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 893730 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@25b23642
[junit4] 2> 893730 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@90471ba[collection1] realtime]
[junit4] 2> 893731 INFO (commitScheduler-3388-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 894730 INFO
(TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[8DF144F35FB6D666])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> 895491 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 895491 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=712331739
[junit4] 2> 895491 INFO (coreCloseExecutor-3390-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@35b474f0
[junit4] 2> 895514 INFO (coreCloseExecutor-3390-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 895517 INFO
(SUITE-SoftAutoCommitTest-seed#[8DF144F35FB6D666]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.SoftAutoCommitTest_8DF144F35FB6D666-001
[junit4] 2> NOTE: test params are: codec=Lucene62,
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=en-PH,
timezone=US/Pacific
[junit4] 2> NOTE: Linux 4.4.0-53-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=12,threads=1,free=238971320,total=528482304
[junit4] 2> NOTE: All tests run in this JVM: [TestCSVResponseWriter,
ActionThrottleTest, TestCursorMarkWithoutUniqueKey,
DistribDocExpirationUpdateProcessorTest, CdcrVersionReplicationTest,
TestLegacyFieldCache, CollectionTooManyReplicasTest, TestStressInPlaceUpdates,
TestReloadDeadlock, TestSolrCLIRunExample, JsonValidatorTest,
TestManagedSchema, TestOverriddenPrefixQueryForCustomFieldType, MultiTermTest,
TestPKIAuthenticationPlugin, DirectSolrConnectionTest, HttpSolrCallGetCoreTest,
URLClassifyProcessorTest, TestSolrCoreSnapshots, TestConfigReload,
SolrIndexConfigTest, TestReloadAndDeleteDocs, TestSearchPerf,
BlockJoinFacetDistribTest, TestSuggestSpellingConverter,
DirectSolrSpellCheckerTest, TestSolrCloudWithKerberosAlt, RankQueryTest,
TestUninvertingReader, SpatialRPTFieldTypeTest, CachingDirectoryFactoryTest,
TestDefaultStatsCache, TestBM25SimilarityFactory,
TestWordDelimiterFilterFactory, DistributedMLTComponentTest,
SolrIndexSplitterTest, SolrIndexMetricsTest, SolrMetricManagerTest,
HttpPartitionTest, TestCloudDeleteByQuery, TestLRUCache,
TestClassicSimilarityFactory, TestSlowCompositeReaderWrapper, JsonLoaderTest,
SliceStateTest, ZkCLITest, TolerantUpdateProcessorTest,
TestConfigSetProperties, SolrCloudExampleTest, CreateCollectionCleanupTest,
StressHdfsTest, TestHdfsCloudBackupRestore, ZkSolrClientTest, TestCharFilters,
TestSystemCollAutoCreate, TestUpdate, IndexSchemaRuntimeFieldTest,
AsyncCallRequestStatusResponseTest, TestRebalanceLeaders,
TestConfigSetsAPIZkFailure, BasicFunctionalityTest, HdfsNNFailoverTest,
ChangedSchemaMergeTest, SystemInfoHandlerTest, HdfsRestartWhileUpdatingTest,
TestMaxScoreQueryParser, TestTolerantUpdateProcessorRandomCloud,
ShardRoutingTest, TestFieldCacheReopen, TestCustomSort,
OutOfBoxZkACLAndCredentialsProvidersTest, ZkControllerTest,
CollectionsAPIDistributedZkTest, TestTrieFacet, TestFreeTextSuggestions,
ConvertedLegacyTest, TestDistributedGrouping, LoggingHandlerTest,
CSVRequestHandlerTest, SaslZkACLProviderTest, TestJsonFacetRefinement,
SolrSlf4jReporterTest, CoreAdminRequestStatusTest,
SchemaVersionSpecificBehaviorTest, TestSolrQueryParserResource,
AnalyticsQueryTest, CloudExitableDirectoryReaderTest, SpatialFilterTest,
TestCustomStream, HdfsLockFactoryTest, TestCollectionAPI, TestSmileRequest,
SolrGangliaReporterTest, SoftAutoCommitTest]
[junit4] Completed [302/696 (1!)] on J1 in 60.45s, 3 tests, 1 failure <<<
FAILURES!
[...truncated 63976 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
