You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/05/20 07:05:20 UTC
[JENKINS] Lucene-Solr-BadApples-8.x-Linux (32bit/jdk1.8.0_201) -
Build # 61 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-8.x-Linux/61/
Java: 32bit/jdk1.8.0_201 -client -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test
Error Message:
.responseHeader.status:200!=0
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.status:200!=0
at __randomizedtesting.SeedInfo.seed([508FD86371B04736:D8DBE7B9DF4C2ACE]:0)
at junit.framework.Assert.fail(Assert.java:57)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:999)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:1026)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:680)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:643)
at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:192)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test
Error Message:
.responseHeader.status:200!=0
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.status:200!=0
at __randomizedtesting.SeedInfo.seed([508FD86371B04736:D8DBE7B9DF4C2ACE]:0)
at junit.framework.Assert.fail(Assert.java:57)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:999)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:1026)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:680)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:643)
at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:192)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13093 lines...]
[junit4] Suite: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality
[junit4] 2> 213075 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[508FD86371B04736]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/init-core-data-001
[junit4] 2> 213076 WARN (SUITE-TestDistributedStatsComponentCardinality-seed#[508FD86371B04736]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14
[junit4] 2> 213076 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[508FD86371B04736]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 213078 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[508FD86371B04736]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9062)
[junit4] 2> 213078 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[508FD86371B04736]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 213189 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/control/cores/collection1
[junit4] 2> 213190 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 213191 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 213191 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 213191 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 213195 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 213195 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 213196 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 213196 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@127d855{/,null,AVAILABLE}
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1cf05a3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42105}
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server Started @213233ms
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=42105, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/control/cores}
[junit4] 2> 213199 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 213199 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-20T05:43:29.175Z
[junit4] 2> 213200 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/control/solr.xml
[junit4] 2> 213210 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 213210 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 213214 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 215055 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 215058 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5c1ef7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 215070 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@18229e6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 215100 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 215100 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 215129 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 215141 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 215141 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 215144 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/control/cores
[junit4] 2> 215144 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 215157 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 215221 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 215303 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 215438 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 215463 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 215464 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 215464 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 215467 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=72.591796875, floorSegmentMB=1.51171875, forceMergeDeletesPctAllowed=15.837187658241055, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=29.041143266994837
[junit4] 2> 215548 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 215548 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 215552 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 215552 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 215552 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=43.8916015625, floorSegmentMB=0.625, forceMergeDeletesPctAllowed=23.24105721994985, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37531141799436607, deletesPctAllowed=38.44202024154307
[junit4] 2> 215553 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ca12f6[collection1] main]
[junit4] 2> 215554 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 215582 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 215676 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 215677 INFO (coreLoadExecutor-698-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1634028498875056128
[junit4] 2> 215678 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 215679 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 215679 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 215679 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 215679 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 215679 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 215679 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 215680 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 215680 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 215684 INFO (searcherExecutor-699-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ca12f6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 216298 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 216299 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 216300 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 216300 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 216300 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 216307 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 216307 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 216307 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 216312 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8c7f35{/,null,AVAILABLE}
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@e4924e{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42349}
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server Started @216349ms
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=42349, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard0/cores}
[junit4] 2> 216315 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 216315 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-20T05:43:32.291Z
[junit4] 2> 216316 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard0/solr.xml
[junit4] 2> 216321 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 216321 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 216322 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 217411 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 217422 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1cb961e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 217430 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1876d03[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 217469 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 217470 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 217516 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 217535 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 217535 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 217538 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard0/cores
[junit4] 2> 217538 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 217540 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 217583 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 217649 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 217850 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 217881 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 217882 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 217882 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 217884 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=72.591796875, floorSegmentMB=1.51171875, forceMergeDeletesPctAllowed=15.837187658241055, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=29.041143266994837
[junit4] 2> 218027 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 218027 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 218029 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 218029 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 218030 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=43.8916015625, floorSegmentMB=0.625, forceMergeDeletesPctAllowed=23.24105721994985, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37531141799436607, deletesPctAllowed=38.44202024154307
[junit4] 2> 218076 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@90545f[collection1] main]
[junit4] 2> 218077 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 218109 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 218211 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 218211 INFO (coreLoadExecutor-712-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1634028501532147712
[junit4] 2> 218215 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 218215 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 218215 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 218215 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 218215 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 218215 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 218216 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 218216 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 218216 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 218220 INFO (searcherExecutor-713-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@90545f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 218441 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard1/cores/collection1
[junit4] 2> 218442 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 218442 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 218442 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 218442 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 218474 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 218474 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 218474 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 218474 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a8466{/,null,AVAILABLE}
[junit4] 2> 218475 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@ee29ee{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39085}
[junit4] 2> 218475 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server Started @218509ms
[junit4] 2> 218475 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=39085, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard1/cores}
[junit4] 2> 218476 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 218476 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 218476 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 218476 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 218476 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 218476 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-20T05:43:34.452Z
[junit4] 2> 218476 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard1/solr.xml
[junit4] 2> 218483 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 218483 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 218485 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 220370 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 220373 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1504a73[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 220382 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1aed367[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 220413 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 220413 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 220436 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 220455 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 220455 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 220457 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard1/cores
[junit4] 2> 220457 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 220466 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 220506 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 220562 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 220756 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 220772 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 220772 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 220772 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard1/cores/collection1/data/]
[junit4] 2> 220780 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=72.591796875, floorSegmentMB=1.51171875, forceMergeDeletesPctAllowed=15.837187658241055, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=29.041143266994837
[junit4] 2> 220854 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 220854 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 220856 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 220856 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 220857 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=43.8916015625, floorSegmentMB=0.625, forceMergeDeletesPctAllowed=23.24105721994985, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37531141799436607, deletesPctAllowed=38.44202024154307
[junit4] 2> 220858 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bbd6b5[collection1] main]
[junit4] 2> 220859 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 220893 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 221001 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 221001 INFO (coreLoadExecutor-726-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1634028504457674752
[junit4] 2> 221002 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 221003 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 221003 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 221003 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 221003 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 221003 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 221003 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 221004 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 221004 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 221008 INFO (searcherExecutor-727-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@bbd6b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 221110 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard2/cores/collection1
[junit4] 2> 221110 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 221111 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 221111 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 221111 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 221112 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 221112 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 221112 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 221113 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f45ad6{/,null,AVAILABLE}
[junit4] 2> 221113 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1581be7{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36057}
[junit4] 2> 221113 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.s.Server Started @221147ms
[junit4] 2> 221113 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=36057, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard2/cores}
[junit4] 2> 221114 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 221114 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 221114 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 221114 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 221114 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 221114 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-20T05:43:37.090Z
[junit4] 2> 221114 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard2/solr.xml
[junit4] 2> 221129 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 221129 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 221130 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 224228 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 224231 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@b526b8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 224245 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@f311cb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 224271 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 224271 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 224325 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 224342 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 224343 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 224346 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard2/cores
[junit4] 2> 224346 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 224354 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 224389 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 224494 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 224695 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 224712 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 224713 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58c10e
[junit4] 2> 224713 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001/tempDir-001/shard2/cores/collection1/data/]
[junit4] 2> 224715 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=72.591796875, floorSegmentMB=1.51171875, forceMergeDeletesPctAllowed=15.837187658241055, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=29.041143266994837
[junit4] 2> 224784 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 224784 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 224785 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 224785 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 224786 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=43.8916015625, floorSegmentMB=0.625, forceMergeDeletesPctAllowed=23.24105721994985, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37531141799436607, deletesPctAllowed=38.44202024154307
[junit4] 2> 224787 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@10427d6[collection1] main]
[junit4] 2> 224788 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 224811 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 224928 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 224928 INFO (coreLoadExecutor-740-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1634028508575432704
[junit4] 2> 224931 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 224931 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 224931 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 224931 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 224932 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 224932 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 224932 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 224933 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 224933 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 224934 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 224934 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[508FD86371B04736]) [ ] o.a.s.h.c.TestDistributedStatsComponentCardinality Building an index of 11929 docs
[junit4] 2> 224937 INFO (searcherExecutor-741-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10427d6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 224952 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1634028508596404224)]} 0 4
[junit4] 2> 224961 INFO (qtp11959788-2316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1634028508606889984)]} 0 3
[junit4] 2> 224967 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1634028508615278592)]} 0 2
[junit4] 2> 224983 INFO (qtp26992313-2337) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1634028508628910080)]} 0 4
[junit4] 2> 224996 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1634028508635201536)]} 0 11
[junit4] 2> 225003 INFO (qtp16847085-2358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1634028508649881600)]} 0 3
[junit4] 2> 225005 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1634028508655124480)]} 0 1
[junit4] 2> 225008 INFO (qtp11959788-2317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1634028508658270208)]} 0 1
[junit4] 2> 225011 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1634028508661415936)]} 0 1
[junit4] 2> 225015 INFO (qtp26992313-2338) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1634028508664561664)]} 0 2
[junit4] 2> 225018 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1634028508668755968)]} 0 1
[junit4] 2> 225021 INFO (qtp16847085-2359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1634028508671901696)]} 0 1
[junit4] 2> 225024 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1634028508675047424)]} 0 1
[junit4] 2> 225027 INFO (qtp11959788-2315) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1634028508678193152)]} 0 1
[junit4] 2> 225030 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1634028508681338880)]} 0 1
[junit4] 2> 225033 INFO (qtp26992313-2336) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1634028508684484608)]} 0 1
[junit4] 2> 225036 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1634028508687630336)]} 0 1
[junit4] 2> 225039 INFO (qtp16847085-2357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1634028508690776064)]} 0 1
[junit4] 2> 225041 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1634028508693921792)]} 0 1
[junit4] 2> 225044 INFO (qtp11959788-2316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1634028508696018944)]} 0 1
[junit4] 2> 225046 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1634028508698116096)]} 0 1
[junit4] 2> 225049 INFO (qtp26992313-2337) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1634028508701261824)]} 0 1
[junit4] 2> 225052 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1634028508704407552)]} 0 1
[junit4] 2> 225054 INFO (qtp16847085-2358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1634028508706504704)]} 0 1
[junit4] 2> 225057 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1634028508709650432)]} 0 1
[junit4] 2> 225060 INFO (qtp11959788-2317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1634028508712796160)]} 0 1
[junit4] 2> 225062 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1634028508714893312)]} 0 1
[junit4] 2> 225064 INFO (qtp26992313-2338) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1634028508718039040)]} 0 0
[junit4] 2> 225067 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1634028508720136192)]} 0 1
[junit4] 2> 225069 INFO (qtp16847085-2359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1634028508723281920)]} 0 0
[junit4] 2> 225071 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1634028508725379072)]} 0 1
[junit4] 2> 225074 INFO (qtp11959788-2315) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1634028508728524800)]} 0 0
[junit4] 2> 225076 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1634028508730621952)]} 0 0
[junit4] 2> 225078 INFO (qtp26992313-2336) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1634028508731670528)]} 0 0
[junit4] 2> 225080 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1634028508733767680)]} 0 0
[junit4] 2> 225082 INFO (qtp16847085-2357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1634028508735864832)]} 0 0
[junit4] 2> 225084 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1634028508737961984)]} 0 1
[junit4] 2> 225086 INFO (qtp11959788-2316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1634028508740059136)]} 0 0
[junit4] 2> 225088 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1634028508742156288)]} 0 0
[junit4] 2> 225089 INFO (qtp26992313-2337) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1634028508744253440)]} 0 0
[junit4] 2> 225091 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1634028508745302016)]} 0 0
[junit4] 2> 225092 INFO (qtp16847085-2358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1634028508747399168)]} 0 0
[junit4] 2> 225094 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1634028508748447744)]} 0 0
[junit4] 2> 225096 INFO (qtp11959788-2317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1634028508750544896)]} 0 0
[junit4] 2> 225098 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1634028508752642048)]} 0 0
[junit4] 2> 225100 INFO (qtp26992313-2338) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1634028508755787776)]} 0 0
[junit4] 2> 225102 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1634028508757884928)]} 0 0
[junit4] 2> 225104 INFO (qtp16847085-2359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1634028508758933504)]} 0 0
[junit4] 2> 225106 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1634028508761030656)]} 0 0
[junit4] 2> 225107 INFO (qtp11959788-2315) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1634028508763127808)]} 0 0
[junit4] 2> 225109 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1634028508765224960)]} 0 0
[junit4] 2> 225111 INFO (qtp26992313-2336) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1634028508766273536)]} 0 0
[junit4] 2> 225113 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[27 (1634028508769419264)]} 0 1
[junit4] 2> 225116 INFO (qtp16847085-2357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[27 (1634028508771516416)]} 0 1
[junit4] 2> 225118 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[28 (1634028508773613568)]} 0 0
[junit4] 2> 225120 INFO (qtp11959788-2316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[28 (1634028508776759296)]} 0 0
[junit4] 2> 225122 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[29 (1634028508778856448)]} 0 0
[junit4] 2> 225125 INFO (qtp26992313-2337) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[29 (1634028508780953600)]} 0 1
[junit4] 2> 225133 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[30 (1634028508784099328)]} 0 6
[junit4] 2> 225135 INFO (qtp16847085-2358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[30 (1634028508791439360)]} 0 0
[junit4] 2> 225137 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[31 (1634028508793536512)]} 0 0
[junit4] 2> 225138 INFO (qtp11959788-2317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[31 (1634028508795633664)]} 0 0
[junit4] 2> 225140 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[32 (1634028508797730816)]} 0 0
[junit4] 2> 225142 INFO (qtp26992313-2338) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[32 (1634028508799827968)]} 0 0
[junit4] 2> 225144 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[33 (1634028508801925120)]} 0 0
[junit4] 2> 225146 INFO (qtp16847085-2359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[33 (1634028508802973696)]} 0 0
[junit4] 2> 225148 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[34 (1634028508805070848)]} 0 0
[junit4] 2> 225150 INFO (qtp11959788-2315) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[34 (1634028508807168000)]} 0 1
[junit4] 2> 225152 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[35 (1634028508809265152)]} 0 1
[junit4] 2> 225154 INFO (qtp26992313-2336) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[35 (1634028508811362304)]} 0 0
[junit4] 2> 225156 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[36 (1634028508813459456)]} 0 0
[junit4] 2> 225157 INFO (qtp16847085-2357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[36 (1634028508815556608)]} 0 0
[junit4] 2> 225160 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[37 (1634028508817653760)]} 0 1
[junit4] 2> 225161 INFO (qtp11959788-2316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[37 (1634028508819750912)]} 0 0
[junit4] 2> 225163 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[38 (1634028508821848064)]} 0 0
[junit4] 2> 225165 INFO (qtp26992313-2337) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[38 (1634028508822896640)]} 0 0
[junit4] 2> 225167 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[39 (1634028508826042368)]} 0 1
[junit4] 2> 225169 INFO (qtp16847085-2358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[39 (1634028508828139520)]} 0 0
[junit4] 2> 225171 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[40 (1634028508829188096)]} 0 0
[junit4] 2> 225172 INFO (qtp11959788-2317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[40 (1634028508831285248)]} 0 0
[junit4] 2> 225179 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[41 (1634028508838625280)]} 0 0
[junit4] 2> 225181 INFO (qtp26992313-2338) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[41 (1634028508840722432)]} 0 0
[junit4] 2> 225183 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[42 (1634028508841771008)]} 0 0
[junit4] 2> 225184 INFO (qtp16847085-2359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[42 (1634028508843868160)]} 0 0
[junit4] 2> 225186 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[43 (1634028508845965312)]} 0 0
[junit4] 2> 225188 INFO (qtp11959788-2315) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[43 (1634028508847013888)]} 0 0
[junit4] 2> 225190 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[44 (1634028508849111040)]} 0 0
[junit4] 2> 225191 INFO (qtp26992313-2336) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[44 (1634028508851208192)]} 0 0
[junit4] 2> 225193 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[45 (1634028508853305344)]} 0 0
[junit4] 2> 225195 INFO (qtp16847085-2357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[45 (1634028508855402496)]} 0 1
[junit4] 2> 225197 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[46 (1634028508856451072)]} 0 0
[junit4] 2> 225198 INFO (qtp11959788-2316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[46 (1634028508858548224)]} 0 0
[junit4] 2> 225200 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[47 (1634028508859596800)]} 0 0
[junit4] 2> 225201 INFO (qtp26992313-2337) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[47 (1634028508861693952)]} 0 0
[junit4] 2> 225203 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[48 (1634028508862742528)]} 0 0
[junit4] 2> 225204 INFO (qtp16847085-2358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[48 (1634028508864839680)]} 0 0
[junit4] 2> 225206 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[49 (1634028508865888256)]} 0 0
[junit4] 2> 225207 INFO (qtp11959788-2317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[49 (1634028508867985408)]} 0 0
[junit4] 2> 225209 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[50 (1634028508870082560)]} 0 0
[junit4] 2> 225212 INFO (qtp26992313-2338) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[50 (1634028508873228288)]} 0 0
[junit4] 2> 225214 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[51 (1634028508874276864)]} 0 0
[junit4] 2> 225215 INFO (qtp16847085-2359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[51 (1634028508875325440)]} 0 0
[junit4] 2> 225216 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[52 (1634028508877422592)]} 0 0
[junit4] 2> 225218 INFO (qtp11959788-2315) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[52 (1634028508878471168)]} 0 0
[junit4] 2> 225220 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[53 (1634028508880568320)]} 0 1
[junit4] 2> 225222 INFO (qtp26992313-2336) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[53 (1634028508882665472)]} 0 0
[junit4] 2> 225223 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[54 (1634028508884762624)]} 0 0
[junit4] 2> 225225 INFO (qtp16847085-2357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[54 (1634028508885811200)]} 0 0
[junit4] 2> 225226 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[55 (1634028508887908352)]} 0 0
[junit4] 2> 225227 INFO (qtp11959788-2316) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[55 (1634028508888956928)]} 0 0
[junit4] 2> 225229 INFO (qtp24777423-2296) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[56 (1634028508891054080)]} 0 0
[junit4] 2> 225231 INFO (qtp26992313-2337) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[56 (1634028508892102656)]} 0 0
[junit4] 2> 225233 INFO (qtp24777423-2294) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[57 (1634028508894199808)]} 0 0
[junit4] 2> 225234 INFO (qtp16847085-2358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[57 (1634028508896296960)]} 0 0
[junit4] 2> 225236 INFO (qtp24777423-2295) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[58 (1634028508897345536)]} 0 0
[junit4] 2> 225237 INFO (qtp11959788-2317) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [colle
[...truncated too long message...]
[junit4] 2> 119742 INFO (closeThreadPool-66-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ebae27: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@ec4385
[junit4] 2> 119742 INFO (closeThreadPool-66-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@72199f: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@8fc9e7
[junit4] 2> 119743 INFO (closeThreadPool-66-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 119743 INFO (closeThreadPool-66-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5efb8a{/,null,UNAVAILABLE}
[junit4] 2> 119743 INFO (closeThreadPool-66-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16490ab: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@fd0946
[junit4] 2> 119743 INFO (closeThreadPool-66-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 119746 INFO (closeThreadPool-66-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 119746 INFO (closeThreadPool-66-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75b01d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b5e5b3
[junit4] 2> 119748 INFO (closeThreadPool-66-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 119748 INFO (closeThreadPool-66-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53b3f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@ec4385
[junit4] 2> 119748 INFO (closeThreadPool-66-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 119748 INFO (closeThreadPool-66-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fc75e9: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b5e5b3
[junit4] 2> 119750 INFO (closeThreadPool-66-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 119750 INFO (closeThreadPool-66-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4effd8: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@ec4385
[junit4] 2> 119768 INFO (closeThreadPool-66-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1109f1c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 119768 INFO (closeThreadPool-66-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@197e8f0{/,null,UNAVAILABLE}
[junit4] 2> 119769 INFO (closeThreadPool-66-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 119769 INFO (closeThreadPool-66-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1109007{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 119769 INFO (closeThreadPool-66-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1710aac{/,null,UNAVAILABLE}
[junit4] 2> 119769 INFO (closeThreadPool-66-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 119770 INFO (closeThreadPool-66-thread-5) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@177decf{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 119770 INFO (closeThreadPool-66-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@13ed10f{/,null,UNAVAILABLE}
[junit4] 2> 119770 INFO (closeThreadPool-66-thread-5) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedStatsComponentCardinality -Dtests.method=test -Dtests.seed=508FD86371B04736 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=sk -Dtests.timezone=Pacific/Chuuk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 47.4s J1 | TestDistributedStatsComponentCardinality.test <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .responseHeader.status:200!=0
[junit4] > at __randomizedtesting.SeedInfo.seed([508FD86371B04736:D8DBE7B9DF4C2ACE]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:57)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:999)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:1026)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:680)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:643)
[junit4] > at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:192)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_508FD86371B04736-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _root_=PostingsFormat(name=LuceneVarGapFixedInterval), string_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=PostingsFormat(name=LuceneFixedGap)}, docValues:{int_i=DocValuesFormat(name=Lucene80), range_facet_l_dv=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Direct), int_i_prehashed_l=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Direct), long_l_prehashed_l=DocValuesFormat(name=Lucene80), long_l=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Direct), string_s_prehashed_l=DocValuesFormat(name=Asserting), id_i1=DocValuesFormat(name=Lucene80), range_facet_i_dv=DocValuesFormat(name=Direct), intDvoDefault=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=617, maxMBSortInHeap=6.796133011764702, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5d8d8a), locale=sk, timezone=Pacific/Chuuk
[junit4] 2> NOTE: Linux 4.18.0-17-generic i386/Oracle Corporation 1.8.0_201 (32-bit)/cpus=8,threads=1,free=156238432,total=295436288
[junit4] 2> NOTE: All tests run in this JVM: [TestDistributedStatsComponentCardinality, TestDistributedStatsComponentCardinality]
[junit4] Completed [5/5 (2!)] on J1 in 47.66s, 1 test, 1 failure <<< FAILURES!
[...truncated 12 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/common-build.xml:1573: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/common-build.xml:1100: There were test failures: 5 suites, 5 tests, 2 failures [seed: 508FD86371B04736]
Total time: 2 minutes 2 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 2/5 failed: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=889cc4fc5c99a94cbb1e23107d8a763963b4dc0b, workspace=/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux
[WARNINGS] Computing warning deltas based on reference build #60
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2