You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/12/31 07:42:08 UTC
[JENKINS] Lucene-Solr-Tests-6.x - Build # 636 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/636/
1 tests failed.
FAILED: org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds
Error Message:
soft530 after hard529 but no hard530: 21544033565363257 !<= 21544033564229486
Stack Trace:
java.lang.AssertionError: soft530 after hard529 but no hard530: 21544033565363257 !<= 21544033564229486
at __randomizedtesting.SeedInfo.seed([F8544A8E92E2702A:A980B30E2391408D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11533 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_F8544A8E92E2702A-001/init-core-data-001
[junit4] 2> 623689 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-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> 623693 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 623693 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-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> 623721 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 623745 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 623859 WARN (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 623861 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 623881 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 623895 WARN (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 623896 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 623896 WARN (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 623899 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 623899 WARN (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 623899 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 623899 WARN (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 623899 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 623900 INFO (coreLoadExecutor-3179-thread-1) [ 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> 623934 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 623981 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 624274 WARN (coreLoadExecutor-3179-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> 624279 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 624289 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] 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> 624289 INFO (coreLoadExecutor-3179-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_F8544A8E92E2702A-001/init-core-data-001/]
[junit4] 2> 624289 WARN (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 624289 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 624289 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 624294 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=39.6474609375, floorSegmentMB=1.6259765625, forceMergeDeletesPctAllowed=27.33027822026538, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.13040547651252413
[junit4] 2> 624413 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 624413 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 624413 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624413 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624414 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7219410591765589]
[junit4] 2> 624414 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@42d7378c[collection1] main]
[junit4] 2> 624414 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 624425 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 624449 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 624449 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 624449 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 624450 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 624450 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 624451 INFO (coreLoadExecutor-3179-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1555214139448623104
[junit4] 2> 624453 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 624454 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 624456 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> 624456 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ ] 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> 624461 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 624461 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 624461 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 624461 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 624462 INFO (searcherExecutor-3180-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@42d7378c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 624492 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 624526 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 624750 WARN (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 624752 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 624757 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ ] 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> 624770 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ 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_F8544A8E92E2702A-001/init-core-data-001/]
[junit4] 2> 624770 WARN (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 624777 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 624777 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.SolrMetricManager Replacing existing reporter 'default' in registry 'solr.core.collection1': [org.apache.solr.metrics.reporters.SolrJmxReporter@34191eee: domain = solr.core.collection1, service url = null, agent id = null]
[junit4] 2> 624780 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 624830 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624830 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624830 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f1b6119[collection1] main]
[junit4] 2> 624831 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 624831 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 624833 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 624833 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 624834 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 624834 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 624834 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 624838 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1555214139854422016
[junit4] 2> 624840 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 624841 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 624841 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 624841 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 624845 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 624846 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f1b6119[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 624853 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=38, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 624858 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 624858 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72152037[collection1] main]
[junit4] 2> 624858 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3b7112f2
[junit4] 2> 624861 INFO (searcherExecutor-3185-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72152037[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 624866 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 624887 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5000 (1555214139884830720)]} 0 20
[junit4] 2> 624889 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5001 (1555214139907899392)]} 0 0
[junit4] 2> 624889 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5002 (1555214139907899393)]} 0 0
[junit4] 2> 624889 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5003 (1555214139907899394)]} 0 0
[junit4] 2> 624890 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5004 (1555214139907899395)]} 0 0
[junit4] 2> 624890 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeRapidAdds
[junit4] 2> 624892 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 624893 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ 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> 624937 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 625028 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 625152 WARN (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ 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> 625153 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 625181 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ 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> 625181 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ 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_F8544A8E92E2702A-001/init-core-data-001/]
[junit4] 2> 625181 WARN (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 625187 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 625187 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 625224 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 625224 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 625235 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3eda9ce9[collection1] main]
[junit4] 2> 625236 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 625236 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 625248 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 625249 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 625254 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 625254 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 625255 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 625255 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 625255 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 625255 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 625257 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 625257 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 625258 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3eda9ce9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5)))}
[junit4] 2> 625269 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 625269 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 625271 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6bb5018[collection1] main]
[junit4] 2> 625272 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6bb5018[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5)))}
[junit4] 2> 625273 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@19613a23
[junit4] 2> 625287 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 625294 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1555214140327329792)]} 0 5
[junit4] 2> 625295 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 625295 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3c0adfc9
[junit4] 2> 625298 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@55f03dbd[collection1] main]
[junit4] 2> 625298 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 625299 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@55f03dbd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_1(6.4.0):C1)))}
[junit4] 2> 625299 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 625300 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[529 (-1555214140337815552)]} 0 0
[junit4] 2> 625800 INFO (commitScheduler-3192-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> 625801 INFO (commitScheduler-3192-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3707c5c7[collection1] main]
[junit4] 2> 625802 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3707c5c7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5)))}
[junit4] 2> 625802 INFO (commitScheduler-3192-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 625804 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[550 (1555214140866297856)]} 0 0
[junit4] 2> 626304 INFO (commitScheduler-3192-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> 626307 INFO (commitScheduler-3192-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65204531[collection1] main]
[junit4] 2> 626307 INFO (commitScheduler-3192-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 626311 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65204531[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_2(6.4.0):C1)))}
[junit4] 2> 626500 INFO (commitScheduler-3191-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> 626500 INFO (commitScheduler-3191-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3c0adfc9
[junit4] 2> 626501 INFO (commitScheduler-3191-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34946057[collection1] main]
[junit4] 2> 626501 INFO (commitScheduler-3191-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 626502 INFO (searcherExecutor-3189-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34946057[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_2(6.4.0):C1)))}
[junit4] 2> 628501 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeDelete-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeDelete
[junit4] 2> 628504 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> 628504 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ 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> 628580 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 628837 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 628903 WARN (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ 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> 628923 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 628928 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ 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> 628929 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ 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_F8544A8E92E2702A-001/init-core-data-001/]
[junit4] 2> 628929 WARN (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
[junit4] 2> 628934 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 628935 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dcf334d
[junit4] 2> 628982 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 628982 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 628982 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b5b20b[collection1] main]
[junit4] 2> 628983 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 628984 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 628990 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 628990 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 628991 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 628991 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 628991 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 628997 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1221750541, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 629003 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 629003 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 629003 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 629003 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 629004 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 629004 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 629004 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b5b20b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_2(6.4.0):C1)))}
[junit4] 2> 629009 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7dc5b877[collection1] main]
[junit4] 2> 629009 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@717f17c1
[junit4] 2> 629010 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7dc5b877[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_2(6.4.0):C1)))}
[junit4] 2> 629023 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 629045 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[529 (1555214144250052608)]} 0 15
[junit4] 2> 629542 INFO (commitScheduler-3196-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> 629551 INFO (commitScheduler-3196-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@69b5ab05[collection1] main]
[junit4] 2> 629552 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@69b5ab05[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_2(6.4.0):C1) Uninverting(_3(6.4.0):C1)))}
[junit4] 2> 629553 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[530 (1555214144797409280)]} 0 0
[junit4] 2> 629553 INFO (commitScheduler-3196-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 630053 INFO (commitScheduler-3196-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> 630286 INFO (commitScheduler-3195-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> 630294 INFO (commitScheduler-3196-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d39ef0f[collection1] main]
[junit4] 2> 630295 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d39ef0f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_2(6.4.0):C1) Uninverting(_3(6.4.0):C1) Uninverting(_4(6.4.0):C1)))}
[junit4] 2> 630295 INFO (commitScheduler-3195-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@68a079ca
[junit4] 2> 630298 INFO (commitScheduler-3195-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c902482[collection1] main]
[junit4] 2> 630298 INFO (commitScheduler-3195-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 630299 INFO (searcherExecutor-3193-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5c902482[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C5) Uninverting(_2(6.4.0):C1) Uninverting(_3(6.4.0):C1) Uninverting(_4(6.4.0):C1)))}
[junit4] 2> 630299 INFO (commitScheduler-3196-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 631498 INFO (TEST-SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds-seed#[F8544A8E92E2702A]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testSoftAndHardCommitMaxTimeMixedAdds
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SoftAutoCommitTest -Dtests.method=testSoftAndHardCommitMaxTimeMixedAdds -Dtests.seed=F8544A8E92E2702A -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fr-FR -Dtests.timezone=America/Virgin -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 3.00s J1 | SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds <<<
[junit4] > Throwable #1: java.lang.AssertionError: soft530 after hard529 but no hard530: 21544033565363257 !<= 21544033564229486
[junit4] > at __randomizedtesting.SeedInfo.seed([F8544A8E92E2702A:A980B30E2391408D]:0)
[junit4] > at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:167)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 631500 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 631500 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1243409795
[junit4] 2> 631509 INFO (coreCloseExecutor-3197-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3e62c666
[junit4] 2> 631530 INFO (coreCloseExecutor-3197-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 631533 INFO (SUITE-SoftAutoCommitTest-seed#[F8544A8E92E2702A]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[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_F8544A8E92E2702A-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {range_facet_l_dv=PostingsFormat(name=Memory doPackFST= true), _version_=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=MockRandom), subject=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=Direct), id=PostingsFormat(name=Memory doPackFST= true), range_facet_i_dv=PostingsFormat(name=MockRandom), text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=MockRandom)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=879, maxMBSortInHeap=5.397191333071642, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=fr-FR, timezone=America/Virgin
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=4,threads=1,free=237531424,total=529006592
[junit4] 2> NOTE: All tests run in this JVM: [BlockDirectoryTest, ReplicationFactorTest, DocValuesMissingTest, TestRawTransformer, TestManagedSchemaThreadSafety, SolrInfoMBeanTest, TestRequestForwarding, DistributedMLTComponentTest, TestReRankQParserPlugin, TestCSVResponseWriter, SuggestComponentTest, DocValuesTest, TestSystemIdResolver, TestBackupRepositoryFactory, DistributedTermsComponentTest, InfoHandlerTest, TestCloudManagedSchema, TestReloadAndDeleteDocs, TestHashQParserPlugin, TestMinMaxOnMultiValuedField, TestRTGBase, TestNamedUpdateProcessors, LeaderInitiatedRecoveryOnCommitTest, TestXmlQParserPlugin, DirectSolrSpellCheckerTest, TestSolrQueryParserDefaultOperatorResource, DistanceFunctionTest, TestReqParamsAPI, TermsComponentTest, SpatialFilterTest, SortSpecParsingTest, DistribJoinFromCollectionTest, SolrMetricReporterTest, DistributedFacetPivotWhiteBoxTest, LoggingHandlerTest, HdfsLockFactoryTest, TestFuzzyAnalyzedSuggestions, IndexSchemaRuntimeFieldTest, TestSurroundQueryParser, TestNonDefinedSimilarityFactory, TestDocBasedVersionConstraints, CollectionsAPIAsyncDistributedZkTest, TestMissingGroups, TestSolrCoreSnapshots, UpdateRequestProcessorFactoryTest, CollectionReloadTest, HdfsTlogReplayBufferedWhileIndexingTest, TestPartialUpdateDeduplication, TestStressVersions, TestDelegationWithHadoopAuth, TestCoreContainer, TermVectorComponentTest, TestPivotHelperCode, JvmMetricsTest, SmileWriterTest, TestFieldSortValues, MultiTermTest, TestCollapseQParserPlugin, TestSolrCloudWithDelegationTokens, TestSolrIndexConfig, BooleanFieldTest, TestConfigReload, DistributedExpandComponentTest, WrapperMergePolicyFactoryTest, SharedFSAutoReplicaFailoverTest, TestQuerySenderListener, HttpSolrCallGetCoreTest, HdfsRestartWhileUpdatingTest, TestExclusionRuleCollectionAccess, CdcrUpdateLogTest, TestLockTree, TestQueryWrapperFilter, ConvertedLegacyTest, ShardRoutingCustomTest, SpellingQueryConverterTest, TestMiniSolrCloudClusterSSL, OverseerStatusTest, TestFiltering, TestQueryTypes, TestBulkSchemaAPI, DirectSolrConnectionTest, TestFieldTypeResource, TestPKIAuthenticationPlugin, BlockJoinFacetSimpleTest, CSVRequestHandlerTest, ShardRoutingTest, RAMDirectoryFactoryTest, HighlighterTest, BasicFunctionalityTest, MultiThreadedOCPTest, SystemInfoHandlerTest, TestSweetSpotSimilarityFactory, TestSimpleTrackingShardHandler, ZkStateWriterTest, TestMacroExpander, IndexBasedSpellCheckerTest, BaseCdcrDistributedZkTest, VersionInfoTest, TestConfigSetProperties, TestRandomRequestDistribution, TestQueryUtils, HdfsNNFailoverTest, EchoParamsTest, TestDistributedGrouping, SoftAutoCommitTest]
[junit4] Completed [255/675 (1!)] on J1 in 7.96s, 3 tests, 1 failure <<< FAILURES!
[...truncated 63802 lines...]
[JENKINS] Lucene-Solr-Tests-6.x - Build # 637 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/637/
1 tests failed.
FAILED: org.apache.solr.metrics.reporters.SolrGangliaReporterTest.testReporter
Error Message:
Stack Trace:
java.util.ConcurrentModificationException
at __randomizedtesting.SeedInfo.seed([21A218F9FAF56493:7E4635CE91F9F7D6]:0)
at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901)
at java.util.ArrayList$Itr.next(ArrayList.java:851)
at org.apache.solr.metrics.reporters.SolrGangliaReporterTest.testReporter(SolrGangliaReporterTest.java:76)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 12554 lines...]
[junit4] Suite: org.apache.solr.metrics.reporters.SolrGangliaReporterTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.metrics.reporters.SolrGangliaReporterTest_21A218F9FAF56493-001/init-core-data-001
[junit4] 2> 1790182 INFO (SUITE-SolrGangliaReporterTest-seed#[21A218F9FAF56493]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1790183 INFO (TEST-SolrGangliaReporterTest.testReporter-seed#[21A218F9FAF56493]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReporter
[junit4] 2> 1790226 INFO (TEST-SolrGangliaReporterTest.testReporter-seed#[21A218F9FAF56493]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1790251 INFO (coreLoadExecutor-6147-thread-1) [ 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> 1790274 WARN (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1790288 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1790305 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1790361 WARN (coreLoadExecutor-6147-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> 1790363 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1790367 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] 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> 1790368 INFO (coreLoadExecutor-6147-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/J0/temp/solr.metrics.reporters.SolrGangliaReporterTest_21A218F9FAF56493-001/init-core-data-001/]
[junit4] 2> 1790368 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ac33ab
[junit4] 2> 1790369 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=36.2919921875, floorSegmentMB=0.9755859375, forceMergeDeletesPctAllowed=28.25252850227145, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1790380 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1790380 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1790380 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1790380 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1790380 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1790381 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d399aac[collection1] main]
[junit4] 2> 1790381 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1790390 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1790409 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1790409 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1790419 INFO (coreLoadExecutor-6147-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1555274742203678720
[junit4] 2> 1790421 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1790421 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1790421 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1790421 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1790421 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1790422 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1790422 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1790422 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1790422 INFO (searcherExecutor-6148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d399aac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1795432 INFO (TEST-SolrGangliaReporterTest.testReporter-seed#[21A218F9FAF56493]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReporter
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrGangliaReporterTest -Dtests.method=testReporter -Dtests.seed=21A218F9FAF56493 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=en-MT -Dtests.timezone=Africa/Maputo -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 5.25s J0 | SolrGangliaReporterTest.testReporter <<<
[junit4] > Throwable #1: java.util.ConcurrentModificationException
[junit4] > at __randomizedtesting.SeedInfo.seed([21A218F9FAF56493:7E4635CE91F9F7D6]:0)
[junit4] > at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901)
[junit4] > at java.util.ArrayList$Itr.next(ArrayList.java:851)
[junit4] > at org.apache.solr.metrics.reporters.SolrGangliaReporterTest.testReporter(SolrGangliaReporterTest.java:76)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1795436 INFO (SUITE-SolrGangliaReporterTest-seed#[21A218F9FAF56493]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1795436 INFO (SUITE-SolrGangliaReporterTest-seed#[21A218F9FAF56493]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=336721720
[junit4] 2> 1795439 INFO (coreCloseExecutor-6153-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@ac502f0
[junit4] 2> 1795445 INFO (coreCloseExecutor-6153-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1795446 INFO (SUITE-SolrGangliaReporterTest-seed#[21A218F9FAF56493]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.metrics.reporters.SolrGangliaReporterTest_21A218F9FAF56493-001
[junit4] 2> NOTE: test params are: codec=Lucene62, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=en-MT, timezone=Africa/Maputo
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=4,threads=1,free=186296080,total=533725184
[junit4] 2> NOTE: All tests run in this JVM: [SolrXmlInZkTest, CacheHeaderTest, TestValueSourceCache, CoreMergeIndexesAdminHandlerTest, TestLocalFSCloudBackupRestore, TestSerializedLuceneMatchVersion, TestHighlightDedupGrouping, SyncSliceTest, DisMaxRequestHandlerTest, SolrRequestParserTest, TestQuerySenderNoQuery, BitVectorTest, SecurityConfHandlerTest, HttpPartitionTest, ConcurrentDeleteAndCreateCollectionTest, TestSolrCloudWithSecureImpersonation, NotRequiredUniqueKeyTest, TestDocSet, TestCorePropertiesReload, TestTolerantUpdateProcessorCloud, DefaultValueUpdateProcessorTest, TestExpandComponent, TestSortByMinMaxFunction, TestOverriddenPrefixQueryForCustomFieldType, AsyncCallRequestStatusResponseTest, JavabinLoaderTest, CurrencyFieldOpenExchangeTest, TestPerFieldSimilarity, TestSolrCoreProperties, TestLRUStatsCache, HdfsRecoveryZkTest, SynonymTokenizerTest, TestSolrConfigHandlerConcurrent, TestDistributedSearch, SuggestComponentContextFilterQueryTest, CloudExitableDirectoryReaderTest, ClassificationUpdateProcessorTest, CloudMLTQParserTest, TemplateUpdateProcessorTest, TestAuthorizationFramework, TestSha256AuthenticationProvider, TestFieldCache, TestJsonFacets, TestSubQueryTransformerDistrib, RequiredFieldsTest, SimpleCollectionCreateDeleteTest, BasicZkTest, ChaosMonkeyNothingIsSafeTest, TestManagedSchemaAPI, TestLazyCores, TestChildDocTransformer, BlockJoinFacetRandomTest, TestComponentsName, PeerSyncTest, ClusterStateTest, TestSolrDeletionPolicy2, PathHierarchyTokenizerFactoryTest, HdfsDirectoryTest, TestFastWriter, CdcrReplicationDistributedZkTest, TestSolrCloudWithHadoopAuthPlugin, QueryElevationComponentTest, TestPseudoReturnFields, DistribDocExpirationUpdateProcessorTest, TestConfigSetsAPI, TestPerFieldSimilarityWithDefaultOverride, TestRandomFaceting, HdfsWriteToMultipleCollectionsTest, TestCustomDocTransformer, TestSizeLimitedDistributedMap, TestJoin, TestCrossCoreJoin, BasicDistributedZkTest, TestRangeQuery, TestSchemaResource, TestRTimerTree, QueryResultKeyTest, UUIDFieldTest, HdfsBasicDistributedZkTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestBinaryResponseWriter, HdfsCollectionsAPIDistributedZkTest, TestReplicaProperties, UUIDUpdateProcessorFallbackTest, TestFieldCacheWithThreads, LeaderFailureAfterFreshStartTest, TestBadConfig, TestFunctionQuery, TestCustomSort, SolrMetricManagerTest, TestGraphMLResponseWriter, XsltUpdateRequestHandlerTest, TestStandardQParsers, QueryEqualityTest, TestBulkSchemaConcurrent, TriLevelCompositeIdRoutingTest, RuleEngineTest, AnalyticsQueryTest, ReturnFieldsTest, TestCopyFieldCollectionResource, NumericFieldsTest, TestNRTOpen, AnalysisErrorHandlingTest, TestCodecSupport, ShardSplitTest, TestFieldTypeCollectionResource, ConjunctionSolrSpellCheckerTest, DeleteShardTest, DistribJoinFromCollectionTest, LeaderFailoverAfterPartitionTest, OverseerTaskQueueTest, ReplaceNodeTest, TestAuthenticationFramework, TestCloudInspectUtil, TestCloudPivotFacet, TestClusterProperties, TestCollectionAPI, TestLeaderElectionZkExpiry, TestLockTree, TestMiniSolrCloudClusterSSL, TestRebalanceLeaders, TestSolrCloudWithKerberosAlt, HdfsSyncSliceTest, HdfsTlogReplayBufferedWhileIndexingTest, ZkStateReaderTest, RulesTest, CachingDirectoryFactoryTest, TestSolrConfigHandler, TestSolrCoreSnapshots, TestBlobHandler, TestRestoreCore, TestSolrConfigHandlerCloud, SolrGangliaReporterTest]
[junit4] Completed [580/675 (1!)] on J0 in 5.28s, 1 test, 1 error <<< FAILURES!
[...truncated 62765 lines...]