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 2016/01/24 22:23:59 UTC

[JENKINS-EA] Lucene-Solr-trunk-Linux (32bit/jdk-9-ea+95) - Build # 15642 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15642/
Java: 32bit/jdk-9-ea+95 -server -XX:+UseSerialGC -XX:-CompactStrings

2 tests failed.
FAILED:  org.apache.lucene.queries.CommonTermsQueryTest.testMinShouldMatch

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([3AFC422BE38C8BF9:1EF44ACE8489C9D7]:0)
	at org.junit.Assert.fail(Assert.java:92)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertTrue(Assert.java:54)
	at org.apache.lucene.queries.CommonTermsQueryTest.testMinShouldMatch(CommonTermsQueryTest.java:268)
	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:520)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	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:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:747)


FAILED:  org.apache.lucene.queries.CommonTermsQueryTest.testExtend

Error Message:
expected:<[2]> but was:<[0]>

Stack Trace:
org.junit.ComparisonFailure: expected:<[2]> but was:<[0]>
	at __randomizedtesting.SeedInfo.seed([3AFC422BE38C8BF9:643603122C3C7C65]:0)
	at org.junit.Assert.assertEquals(Assert.java:125)
	at org.junit.Assert.assertEquals(Assert.java:147)
	at org.apache.lucene.queries.CommonTermsQueryTest.testExtend(CommonTermsQueryTest.java:392)
	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:520)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	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:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:747)




Build Log:
[...truncated 7039 lines...]
   [junit4] Suite: org.apache.lucene.queries.CommonTermsQueryTest
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=CommonTermsQueryTest -Dtests.method=testMinShouldMatch -Dtests.seed=3AFC422BE38C8BF9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=bs-Cyrl-BA -Dtests.timezone=Chile/EasterIsland -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.05s J1 | CommonTermsQueryTest.testMinShouldMatch <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3AFC422BE38C8BF9:1EF44ACE8489C9D7]:0)
   [junit4]    > 	at org.apache.lucene.queries.CommonTermsQueryTest.testMinShouldMatch(CommonTermsQueryTest.java:268)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:747)
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=CommonTermsQueryTest -Dtests.method=testExtend -Dtests.seed=3AFC422BE38C8BF9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=bs-Cyrl-BA -Dtests.timezone=Chile/EasterIsland -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.03s J1 | CommonTermsQueryTest.testExtend <<<
   [junit4]    > Throwable #1: org.junit.ComparisonFailure: expected:<[2]> but was:<[0]>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3AFC422BE38C8BF9:643603122C3C7C65]:0)
   [junit4]    > 	at org.apache.lucene.queries.CommonTermsQueryTest.testExtend(CommonTermsQueryTest.java:392)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:747)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {date=PostingsFormat(name=LuceneVarGapFixedInterval), field=PostingsFormat(name=LuceneVarGapFixedInterval), docid=PostingsFormat(name=Memory doPackFST= false), titleTokenized=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=PostingsFormat(name=Memory doPackFST= false), body=PostingsFormat(name=LuceneVarGapFixedInterval), title=FST50}, docValues:{docid_intDV=DocValuesFormat(name=Lucene54), titleDV=DocValuesFormat(name=Memory)}, sim=RandomSimilarity(queryNorm=false,coord=yes): {field=DFI(Standardized), titleTokenized=DFR GLZ(0.3), body=DFR I(F)Z(0.3)}, locale=bs-Cyrl-BA, timezone=Chile/EasterIsland
   [junit4]   2> NOTE: Linux 3.19.0-47-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=49376192,total=64880640
   [junit4]   2> NOTE: All tests run in this JVM: [TestPayloadExplanations, TestCustomScoreExplanations, TestBoostedQuery, TestLongNormValueSource, CommonTermsQueryTest]
   [junit4] Completed [15/20 (1!)] on J1 in 2.55s, 7 tests, 2 failures <<< FAILURES!

[...truncated 27 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:675: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:619: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:57: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build.xml:475: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:2154: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:58: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1424: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:981: There were test failures: 20 suites, 107 tests, 2 failures [seed: 3AFC422BE38C8BF9]

Total time: 18 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



[JENKINS-EA] Lucene-Solr-trunk-Linux (64bit/jdk-9-ea+95) - Build # 15643 - Still Failing!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15643/
Java: 64bit/jdk-9-ea+95 -XX:-UseCompressedOops -XX:+UseParallelGC -XX:-CompactStrings

1 tests failed.
FAILED:  org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test

Error Message:
int_i: goodEst=13951, poorEst=13970, real=13971, p=q=id:[540+TO+14510]&rows=0&stats=true&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_int_i}int_i&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_int_i}int_i&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_long_l}long_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_long_l}long_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_string_s}string_s&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_string_s}string_s&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l

Stack Trace:
java.lang.AssertionError: int_i: goodEst=13951, poorEst=13970, real=13971, p=q=id:[540+TO+14510]&rows=0&stats=true&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_int_i}int_i&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_int_i}int_i&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_long_l}long_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_long_l}long_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_string_s}string_s&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_string_s}string_s&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l
	at __randomizedtesting.SeedInfo.seed([81F83400556CE568:9AC0BDAFB908890]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:216)
	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:520)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:965)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:940)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	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:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:747)




Build Log:
[...truncated 11034 lines...]
   [junit4] Suite: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/init-core-data-001
   [junit4]   2> 1610317 INFO  (SUITE-TestDistributedStatsComponentCardinality-seed#[81F83400556CE568]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 1610317 INFO  (SUITE-TestDistributedStatsComponentCardinality-seed#[81F83400556CE568]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1610370 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1610371 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1610372 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3c278701{/,null,AVAILABLE}
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.ServerConnector Started ServerConnector@1871d3a8{HTTP/1.1}{127.0.0.1:58641}
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.Server Started @1612059ms
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=58641, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores}
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@deb6432
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control'
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1610375 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/solr.xml
   [junit4]   2> 1610378 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores
   [junit4]   2> 1610378 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer New CoreContainer 1470433291
   [junit4]   2> 1610378 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control]
   [junit4]   2> 1610378 WARN  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/lib to classpath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/lib
   [junit4]   2> 1610379 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1610379 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1610380 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1610380 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1610380 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1610380 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1610380 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores
   [junit4]   2> 1610380 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/, configSet=collection1}
   [junit4]   2> 1610380 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1610381 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1610381 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 1610381 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1610381 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1610381 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1610381 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1610381 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1610381 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1610397 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1610405 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1610419 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1610423 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1610481 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1610490 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1610490 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1610492 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1610494 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1610494 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1610494 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1610495 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1610496 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1610496 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1610496 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1610496 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@38981d8f
   [junit4]   2> 1610496 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1/data
   [junit4]   2> 1610496 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1/data/index/
   [junit4]   2> 1610496 WARN  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1610497 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1/data/index
   [junit4]   2> 1610497 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=30.6767578125, floorSegmentMB=1.1650390625, forceMergeDeletesPctAllowed=28.657324113915948, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1610497 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@3a52f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5969f0e5),segFN=segments_1,generation=1}
   [junit4]   2> 1610497 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1610498 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1/data/
   [junit4]   2> 1610498 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/control/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1610499 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1610499 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1610499 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1610499 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1610500 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1610501 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1610501 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1610502 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1610502 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1610504 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1610504 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1610505 WARN  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1610505 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1610506 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1610506 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1610506 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1610506 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1610506 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46552056845247847]
   [junit4]   2> 1610506 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@3a52f4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5969f0e5),segFN=segments_1,generation=1}
   [junit4]   2> 1610507 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1610507 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@20d01a8d[collection1] main]
   [junit4]   2> 1610507 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1610507 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1610507 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1610507 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1610575 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1610903 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1610903 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1610903 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1610903 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1610903 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1610903 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1610904 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1610904 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1610904 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1610904 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1524290984552169472
   [junit4]   2> 1610904 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1610904 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1610904 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1610904 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1610905 INFO  (searcherExecutor-6441-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@20d01a8d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1610906 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1524290984552169472
   [junit4]   2> 1610906 INFO  (coreLoadExecutor-6440-thread-1) [    ] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1611431 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1611432 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1611432 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a8907a6{/,null,AVAILABLE}
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.ServerConnector Started ServerConnector@5bd24adc{HTTP/1.1}{127.0.0.1:54854}
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.Server Started @1613117ms
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=54854, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores}
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@deb6432
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0'
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1611433 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1611436 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores
   [junit4]   2> 1611436 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer New CoreContainer 181799425
   [junit4]   2> 1611436 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0]
   [junit4]   2> 1611436 WARN  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/lib to classpath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/lib
   [junit4]   2> 1611436 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1611437 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1611437 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1611437 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1611437 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1611437 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1611438 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores
   [junit4]   2> 1611438 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/, configSet=collection1}
   [junit4]   2> 1611438 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1611438 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1611438 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 1611438 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1611438 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1611439 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1611439 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1611439 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1611439 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1611452 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1611458 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1611471 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1611474 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1611531 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1611534 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1611534 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1611536 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1611538 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1611538 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1611539 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1611540 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1611540 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1611540 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1611540 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1611540 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@38981d8f
   [junit4]   2> 1611540 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data
   [junit4]   2> 1611540 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data/index/
   [junit4]   2> 1611541 WARN  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1611541 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 1611541 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=30.6767578125, floorSegmentMB=1.1650390625, forceMergeDeletesPctAllowed=28.657324113915948, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1611541 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@57393b9d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3aae3598),segFN=segments_1,generation=1}
   [junit4]   2> 1611541 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1611541 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data/
   [junit4]   2> 1611542 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1611543 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1611544 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1611545 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1611546 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1611546 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1611547 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1611549 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1611550 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1611550 WARN  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1611551 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1611551 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1611551 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46552056845247847]
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@57393b9d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3aae3598),segFN=segments_1,generation=1}
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ad8f8bc[collection1] main]
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1611552 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1611602 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1611852 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1611852 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1611852 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1611852 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1611852 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1611852 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1611852 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1611852 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1611852 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1524290985546219520
   [junit4]   2> 1611852 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1611853 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1611853 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1611853 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1611853 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1611854 INFO  (searcherExecutor-6452-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ad8f8bc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1611854 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1524290985546219520
   [junit4]   2> 1611854 INFO  (coreLoadExecutor-6451-thread-1) [    ] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1611939 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1611939 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.a.s.h.c.TestDistributedStatsComponentCardinality Building an index of 14585 docs
   [junit4]   2> 1611948 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1 (1524290985646882816)]} 0 0
   [junit4]   2> 1611951 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1 (1524290985648979968)]} 0 0
   [junit4]   2> 1611953 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[2 (1524290985652125696)]} 0 0
   [junit4]   2> 1611954 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[2 (1524290985653174272)]} 0 0
   [junit4]   2> 1611956 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[3 (1524290985655271424)]} 0 0
   [junit4]   2> 1611958 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[3 (1524290985656320000)]} 0 0
   [junit4]   2> 1611959 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[4 (1524290985658417152)]} 0 0
   [junit4]   2> 1611961 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[4 (1524290985659465728)]} 0 0
   [junit4]   2> 1611963 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[5 (1524290985661562880)]} 0 0
   [junit4]   2> 1611964 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[5 (1524290985663660032)]} 0 0
   [junit4]   2> 1611966 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[6 (1524290985664708608)]} 0 0
   [junit4]   2> 1611967 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[6 (1524290985666805760)]} 0 0
   [junit4]   2> 1611968 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[7 (1524290985667854336)]} 0 0
   [junit4]   2> 1611970 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[7 (1524290985669951488)]} 0 0
   [junit4]   2> 1611971 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[8 (1524290985671000064)]} 0 0
   [junit4]   2> 1611973 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[8 (1524290985673097216)]} 0 0
   [junit4]   2> 1611975 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[9 (1524290985675194368)]} 0 0
   [junit4]   2> 1611976 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[9 (1524290985676242944)]} 0 0
   [junit4]   2> 1611978 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[10 (1524290985678340096)]} 0 0
   [junit4]   2> 1611979 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[10 (1524290985679388672)]} 0 0
   [junit4]   2> 1611981 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[11 (1524290985681485824)]} 0 0
   [junit4]   2> 1611983 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[11 (1524290985683582976)]} 0 0
   [junit4]   2> 1611984 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[12 (1524290985684631552)]} 0 0
   [junit4]   2> 1611986 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[12 (1524290985686728704)]} 0 0
   [junit4]   2> 1611987 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[13 (1524290985687777280)]} 0 0
   [junit4]   2> 1611989 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[13 (1524290985688825856)]} 0 0
   [junit4]   2> 1611990 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[14 (1524290985690923008)]} 0 0
   [junit4]   2> 1611991 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[14 (1524290985691971584)]} 0 0
   [junit4]   2> 1611993 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[15 (1524290985694068736)]} 0 0
   [junit4]   2> 1611995 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[15 (1524290985695117312)]} 0 0
   [junit4]   2> 1611996 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[16 (1524290985697214464)]} 0 0
   [junit4]   2> 1611997 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[16 (1524290985698263040)]} 0 0
   [junit4]   2> 1611999 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[17 (1524290985700360192)]} 0 0
   [junit4]   2> 1612000 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[17 (1524290985701408768)]} 0 0
   [junit4]   2> 1612002 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[18 (1524290985703505920)]} 0 0
   [junit4]   2> 1612003 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[18 (1524290985704554496)]} 0 0
   [junit4]   2> 1612005 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[19 (1524290985706651648)]} 0 0
   [junit4]   2> 1612007 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[19 (1524290985707700224)]} 0 0
   [junit4]   2> 1612008 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[20 (1524290985709797376)]} 0 0
   [junit4]   2> 1612009 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[20 (1524290985710845952)]} 0 0
   [junit4]   2> 1612011 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[21 (1524290985712943104)]} 0 0
   [junit4]   2> 1612012 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[21 (1524290985713991680)]} 0 0
   [junit4]   2> 1612014 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[22 (1524290985716088832)]} 0 0
   [junit4]   2> 1612015 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[22 (1524290985717137408)]} 0 0
   [junit4]   2> 1612017 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[23 (1524290985719234560)]} 0 0
   [junit4]   2> 1612019 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[23 (1524290985720283136)]} 0 0
   [junit4]   2> 1612020 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[24 (1524290985722380288)]} 0 0
   [junit4]   2> 1612021 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[24 (1524290985723428864)]} 0 0
   [junit4]   2> 1612023 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[25 (1524290985725526016)]} 0 0
   [junit4]   2> 1612024 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[25 (1524290985726574592)]} 0 0
   [junit4]   2> 1612026 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[26 (1524290985728671744)]} 0 0
   [junit4]   2> 1612027 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[26 (1524290985729720320)]} 0 0
   [junit4]   2> 1612029 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[27 (1524290985731817472)]} 0 0
   [junit4]   2> 1612031 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[27 (1524290985733914624)]} 0 0
   [junit4]   2> 1612032 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[28 (1524290985734963200)]} 0 0
   [junit4]   2> 1612034 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[28 (1524290985737060352)]} 0 0
   [junit4]   2> 1612035 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[29 (1524290985738108928)]} 0 0
   [junit4]   2> 1612043 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[29 (1524290985746497536)]} 0 0
   [junit4]   2> 1612045 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[30 (1524290985748594688)]} 0 0
   [junit4]   2> 1612046 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[30 (1524290985749643264)]} 0 0
   [junit4]   2> 1612048 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[31 (1524290985751740416)]} 0 0
   [junit4]   2> 1612049 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[31 (1524290985752788992)]} 0 0
   [junit4]   2> 1612051 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[32 (1524290985754886144)]} 0 0
   [junit4]   2> 1612053 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[32 (1524290985756983296)]} 0 0
   [junit4]   2> 1612055 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[33 (1524290985759080448)]} 0 0
   [junit4]   2> 1612056 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[33 (1524290985760129024)]} 0 0
   [junit4]   2> 1612058 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[34 (1524290985762226176)]} 0 0
   [junit4]   2> 1612059 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[34 (1524290985763274752)]} 0 0
   [junit4]   2> 1612061 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[35 (1524290985765371904)]} 0 0
   [junit4]   2> 1612062 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[35 (1524290985766420480)]} 0 0
   [junit4]   2> 1612064 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[36 (1524290985768517632)]} 0 0
   [junit4]   2> 1612065 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[36 (1524290985769566208)]} 0 0
   [junit4]   2> 1612067 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[37 (1524290985771663360)]} 0 0
   [junit4]   2> 1612068 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[37 (1524290985772711936)]} 0 0
   [junit4]   2> 1612070 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[38 (1524290985774809088)]} 0 0
   [junit4]   2> 1612071 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[38 (1524290985775857664)]} 0 0
   [junit4]   2> 1612073 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[39 (1524290985777954816)]} 0 0
   [junit4]   2> 1612074 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[39 (1524290985779003392)]} 0 0
   [junit4]   2> 1612076 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[40 (1524290985781100544)]} 0 0
   [junit4]   2> 1612077 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[40 (1524290985782149120)]} 0 0
   [junit4]   2> 1612081 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[41 (1524290985785294848)]} 0 0
   [junit4]   2> 1612082 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[41 (1524290985787392000)]} 0 0
   [junit4]   2> 1612084 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[42 (1524290985789489152)]} 0 0
   [junit4]   2> 1612086 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[42 (1524290985791586304)]} 0 0
   [junit4]   2> 1612087 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[43 (1524290985792634880)]} 0 0
   [junit4]   2> 1612089 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[43 (1524290985794732032)]} 0 0
   [junit4]   2> 1612091 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[44 (1524290985796829184)]} 0 0
   [junit4]   2> 1612092 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[44 (1524290985797877760)]} 0 0
   [junit4]   2> 1612094 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[45 (1524290985799974912)]} 0 0
   [junit4]   2> 1612095 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[45 (1524290985801023488)]} 0 0
   [junit4]   2> 1612097 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[46 (1524290985803120640)]} 0 0
   [junit4]   2> 1612098 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[46 (1524290985804169216)]} 0 0
   [junit4]   2> 1612100 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[47 (1524290985806266368)]} 0 0
   [junit4]   2> 1612102 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[47 (1524290985807314944)]} 0 0
   [junit4]   2> 1612103 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[48 (1524290985809412096)]} 0 0
   [junit4]   2> 1612104 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[48 (1524290985810460672)]} 0 0
   [junit4]   2> 1612106 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[49 (1524290985812557824)]} 0 0
   [junit4]   2> 1612108 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[49 (1524290985814654976)]} 0 0
   [junit4]   2> 1612109 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[50 (1524290985815703552)]} 0 0
   [junit4]   2> 1612111 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[50 (1524290985817800704)]} 0 0
   [junit4]   2> 1612112 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[51 (1524290985818849280)]} 0 0
   [junit4]   2> 1612114 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[51 (1524290985820946432)]} 0 0
   [junit4]   2> 1612115 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[52 (1524290985821995008)]} 0 0
   [junit4]   2> 1612117 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[52 (1524290985824092160)]} 0 0
   [junit4]   2> 1612118 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[53 (1524290985825140736)]} 0 0
   [junit4]   2> 1612120 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[53 (1524290985827237888)]} 0 0
   [junit4]   2> 1612121 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[54 (1524290985828286464)]} 0 0
   [junit4]   2> 1612123 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[54 (1524290985830383616)]} 0 0
   [junit4]   2> 1612124 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[55 (1524290985831432192)]} 0 0
   [junit4]   2> 1612126 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[55 (1524290985833529344)]} 0 0
   [junit4]   2> 1612127 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[56 (1524290985834577920)]} 0 0
   [junit4]   2> 1612129 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[56 (1524290985836675072)]} 0 0
   [junit4]   2> 1612131 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[57 (1524290985838772224)]} 0 0
   [junit4]   2> 1612132 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[57 (1524290985839820800)]} 0 0
   [junit4]   2> 1612134 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[58 (1524290985841917952)]} 0 0
   [junit4]   2> 1612135 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[58 (1524290985842966528)]} 0 0
   [junit4]   2> 1612137 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[59 (1524290985845063680)]} 0 0
   [junit4]   2> 1612139 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[59 (1524290985847160832)]} 0 0
   [junit4]   2> 1612140 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[60 (1524290985848209408)]} 0 0
   [junit4]   2> 1612142 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[60 (1524290985850306560)]} 0 0
   [junit4]   2> 1612143 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[61 (1524290985851355136)]} 0 0
   [junit4]   2> 1612145 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[61 (1524290985852403712)]} 0 0
   [junit4]   2> 1612146 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[62 (1524290985854500864)]} 0 0
   [junit4]   2> 1612148 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[62 (1524290985856598016)]} 0 0
   [junit4]   2> 1612149 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[63 (1524290985857646592)]} 0 0
   [junit4]   2> 1612151 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[63 (1524290985859743744)]} 0 0
   [junit4]   2> 1612153 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[64 (1524290985861840896)]} 0 0
   [junit4]   2> 1612155 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[64 (1524290985863938048)]} 0 0
   [junit4]   2> 1612157 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[65 (1524290985864986624)]} 0 0
   [junit4]   2> 1612158 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[65 (1524290985867083776)]} 0 0
   [junit4]   2> 1612160 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[66 (1524290985869180928)]} 0 0
   [junit4]   2> 1612161 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[66 (1524290985870229504)]} 0 0
   [junit4]   2> 1612163 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[67 (1524290985872326656)]} 0 0
   [junit4]   2> 1612164 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[67 (1524290985873375232)]} 0 0
   [junit4]   2> 1612166 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[68 (1524290985875472384)]} 0 0
   [junit4]   2> 1612167 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[68 (1524290985876520960)]} 0 0
   [junit4]   2> 1612169 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[69 (1524290985878618112)]} 0 0
   [junit4]   2> 1612170 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[69 (1524290985879666688)]} 0 0
   [junit4]   2> 1612172 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[70 (1524290985881763840)]} 0 0
   [junit4]   2> 1612173 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[70 (1524290985882812416)]} 0 0
   [junit4]   2> 1612175 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[71 (1524290985884909568)]} 0 0
   [junit4]   2> 1612176 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[71 (1524290985885958144)]} 0 0
   [junit4]   2> 1612178 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[72 (1524290985888055296)]} 0 0
   [junit4]   2> 1612179 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[72 (1524290985889103872)]} 0 0
   [junit4]   2> 1612180 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[73 (1524290985890152448)]} 0 0
   [junit4]   2> 1612182 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[73 (1524290985892249600)]} 0 0
   [junit4]   2> 1612183 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[74 (1524290985893298176)]} 0 0
   [junit4]   2> 1612185 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[74 (1524290985895395328)]} 0 0
   [junit4]   2> 1612186 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[75 (1524290985896443904)]} 0 0
   [junit4]   2> 1612188 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[75 (1524290985898541056)]} 0 0
   [junit4]   2> 1612189 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[76 (1524290985899589632)]} 0 0
   [junit4]   2> 1612191 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[76 (1524290985901686784)]} 0 0
   [junit4]   2> 1612192 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[77 (1524290985902735360)]} 0 0
   [junit4]   2> 1612194 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[77 (1524290985904832512)]} 0 0
   [junit4]   2> 1612195 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[78 (1524290985905881088)]} 0 0
   [junit4]   2> 1612197 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[78 (1524290985906929664)]} 0 0
   [junit4]   2> 1612198 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[79 (1524290985909026816)]} 0 0
   [junit4]   2> 1612200 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[79 (1524290985911123968)]} 0 0
   [junit4]   2> 1612201 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[80 (1524290985912172544)]} 0 0
   [junit4]   2> 1612203 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[80 (1524290985914269696)]} 0 0
   [junit4]   2> 1612204 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[81 (1524290985915318272)]} 0 0
   [junit4]   2> 1612205 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[81 (1524290985916366848)]} 0 0
   [junit4]   2> 1612207 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[82 (1524290985918464000)]} 0 0
   [junit4]   2> 1612208 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[82 (1524290985919512576)]} 0 0
   [junit4]   2> 1612210 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[83 (1524290985921609728)]} 0 0
   [junit4]   2> 1612211 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[83 (1524290985922658304)]} 0 0
   [junit4]   2> 1612213 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[84 (1524290985924755456)]} 0 0
   [junit4]   2> 1612214 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[84 (1524290985925804032)]} 0 0
   [junit4]   2> 1612216 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[85 (1524290985927901184)]} 0 0
   [junit4]   2> 1612217 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[85 (1524290985928949760)]} 0 0
   [junit4]   2> 1612219 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[86 (1524290985931046912)]} 0 0
   [junit4]   2> 1612220 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[86 (1524290985932095488)]} 0 0
   [junit4]   2> 1612222 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[87 (1524290985934192640)]} 0 0
   [junit4]   2> 1612223 INFO  (qtp1832996378-14829) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[87 (1524290985935241216)]} 0 0
   [junit4]   2> 1612225 INFO  (qtp1240584686-14809) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[88 (1524290985936289792)]} 0 0
   [junit4]   2> 1612226 INFO  (qtp1832996378-14827) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[88 (1524290985938386944)]} 0 0
   [junit4]   2> 1612228 INFO  (qtp1240584686-14810) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[89 (1524290985940484096)]} 0 0
   [junit4]   2> 1612229 INFO  (qtp1832996378-14828) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[89 (1524290985941532672)]} 0 0
   [junit4]   2> 1612231 INFO  (qtp1240584686-14811) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[90 (1524290985943629824)]} 0 0
   [junit4]   2> 1612232 INFO  (qtp1

[...truncated too long message...]

ponentCardinality_81F83400556CE568-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 1676423 INFO  (TEST-TestDistributedStatsComponentCardinality.test-seed#[81F83400556CE568]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4a8907a6{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDistributedStatsComponentCardinality -Dtests.method=test -Dtests.seed=81F83400556CE568 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-MH -Dtests.timezone=Atlantic/Canary -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 66.1s J0 | TestDistributedStatsComponentCardinality.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: int_i: goodEst=13951, poorEst=13970, real=13971, p=q=id:[540+TO+14510]&rows=0&stats=true&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_int_i}int_i&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_int_i}int_i&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_int_i_prehashed_l+hllPreHashed%3Dtrue}int_i_prehashed_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_long_l}long_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_long_l}long_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_long_l_prehashed_l+hllPreHashed%3Dtrue}long_l_prehashed_l&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_string_s}string_s&stats.field={!cardinality%3D0.009013848089941212+key%3Dlow_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_string_s}string_s&stats.field={!cardinality%3D0.5090138480899412+key%3Dhigh_string_s_prehashed_l+hllPreHashed%3Dtrue}string_s_prehashed_l
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([81F83400556CE568:9AC0BDAFB908890]:0)
   [junit4]    > 	at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:216)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:965)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:940)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:747)
   [junit4]   2> 1676426 INFO  (SUITE-TestDistributedStatsComponentCardinality-seed#[81F83400556CE568]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_81F83400556CE568-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {int_i=PostingsFormat(name=LuceneFixedGap), range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=Lucene50(blocksize=128), intDefault=PostingsFormat(name=Memory doPackFST= false), int_i_prehashed_l=PostingsFormat(name=LuceneFixedGap), long_l_prehashed_l=PostingsFormat(name=LuceneFixedGap), range_facet_l=PostingsFormat(name=Memory doPackFST= false), long_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _version_=PostingsFormat(name=Memory doPackFST= false), string_s_prehashed_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), string_s=PostingsFormat(name=LuceneFixedGap), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=en-MH, timezone=Atlantic/Canary
   [junit4]   2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=134090440,total=525860864
   [junit4]   2> NOTE: All tests run in this JVM: [TestSchemaVersionResource, CdcrVersionReplicationTest, TestDocSet, TestCloudManagedSchemaConcurrent, TestLazyCores, TestSchemaNameResource, DeleteInactiveReplicaTest, CloneFieldUpdateProcessorFactoryTest, TestRawResponseWriter, TestSolrCLIRunExample, CoreAdminCreateDiscoverTest, TestSolrConfigHandler, InfoHandlerTest, DistributedQueryElevationComponentTest, IndexSchemaTest, TestSimpleQParserPlugin, DisMaxRequestHandlerTest, XsltUpdateRequestHandlerTest, DistributedDebugComponentTest, TestMiniSolrCloudCluster, CurrencyFieldOpenExchangeTest, BlockCacheTest, PathHierarchyTokenizerFactoryTest, RequestLoggingTest, SuggestComponentContextFilterQueryTest, BasicDistributedZkTest, ResourceLoaderTest, TestImplicitCoreProperties, UUIDUpdateProcessorFallbackTest, TestCloudInspectUtil, DistributedSuggestComponentTest, FullHLLTest, TestLMDirichletSimilarityFactory, TestValueSourceCache, HdfsDirectoryTest, TestHighlightDedupGrouping, HdfsNNFailoverTest, TestFileDictionaryLookup, TestPerFieldSimilarityClassic, HdfsRestartWhileUpdatingTest, PreAnalyzedFieldTest, JavabinLoaderTest, DistributedSpellCheckComponentTest, SparseHLLTest, TestRangeQuery, SolrCoreTest, CloudExitableDirectoryReaderTest, TestLRUCache, ConjunctionSolrSpellCheckerTest, JSONWriterTest, HdfsChaosMonkeyNothingIsSafeTest, TestNRTOpen, SyncSliceTest, DocumentAnalysisRequestHandlerTest, TestBinaryField, SolrCloudExampleTest, ConnectionReuseTest, TestManagedSchemaFieldResource, DefaultValueUpdateProcessorTest, OutputWriterTest, TestExclusionRuleCollectionAccess, SOLR749Test, TestBadConfig, TestArbitraryIndexDir, ParsingFieldUpdateProcessorsTest, DateRangeFieldTest, TestClassicSimilarityFactory, LeaderElectionIntegrationTest, CdcrReplicationDistributedZkTest, TestFunctionQuery, ReplicationFactorTest, TestRandomFaceting, TestPhraseSuggestions, TestInitQParser, QueryElevationComponentTest, ExternalFileFieldSortTest, RequiredFieldsTest, TestConfigSetsAPI, ZkNodePropsTest, SortSpecParsingTest, TestStandardQParsers, SignatureUpdateProcessorFactoryTest, TestLuceneMatchVersion, TestReplicationHandlerBackup, EchoParamsTest, TestReplicaProperties, SpatialRPTFieldTypeTest, AnalysisErrorHandlingTest, TestFastOutputStream, TestMiniSolrCloudClusterSSL, AsyncCallRequestStatusResponseTest, TestComponentsName, TestAtomicUpdateErrorCases, TestXmlQParser, TestPseudoReturnFields, TestShortCircuitedRequests, DistribJoinFromCollectionTest, FullSolrCloudDistribCmdsTest, TestFaceting, TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest, TestJoin, SimpleFacetsTest, SolrCmdDistributorTest, TestSort, DirectUpdateHandlerTest, TestIndexSearcher, SoftAutoCommitTest, CurrencyFieldXmlFileTest, AnalysisAfterCoreReloadTest, CoreAdminHandlerTest, TestStressLucene, TestTrie, PolyFieldTest, WordBreakSolrSpellCheckerTest, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, IndexBasedSpellCheckerTest, TestOmitPositions, RequestHandlersTest, TestIndexingPerformance, MoreLikeThisHandlerTest, IndexSchemaRuntimeFieldTest, TestJmxIntegration, ReturnFieldsTest, TestAnalyzedSuggestions, BinaryUpdateRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache, TestTolerantSearch, TestEmbeddedSolrServerConstructors, ActionThrottleTest, BaseCdcrDistributedZkTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CleanupOldIndexTest, CollectionStateFormat2Test, ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DistributedQueueTest, DistributedVersionInfoTest, HttpPartitionTest, RestartWhileUpdatingTest, RollingRestartTest, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDownShardTolerantSearch, TestRequestForwarding, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, HdfsRecoverLeaseTest, HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsUnloadDistributedZkTest, StressHdfsTest, TestClusterStateMutator, ZkStateReaderTest, RuleEngineTest, DirectoryFactoryTest, ExitableDirectoryReaderTest, QueryResultKeyTest, TestConfigSetImmutable, TestConfigSetProperties, TestConfigSets, TestDynamicLoading, TestSolrConfigHandlerCloud, DistributedFacetPivotLongTailTest, DistributedFacetPivotSmallTest, FacetPivotSmallTest, SuggestComponentTest, TestDistributedStatsComponentCardinality]
   [junit4] Completed [540/577 (1!)] on J0 in 66.13s, 1 test, 1 failure <<< FAILURES!

[...truncated 143 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:684: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:524: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1424: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:981: There were test failures: 577 suites (8 ignored), 2316 tests, 1 failure, 115 ignored (70 assumptions) [seed: 81F83400556CE568]

Total time: 50 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any