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/02/02 05:55:04 UTC

[JENKINS-EA] Lucene-Solr-trunk-Linux (64bit/jdk-9-ea+102) - Build # 15738 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15738/
Java: 64bit/jdk-9-ea+102 -XX:+UseCompressedOops -XX:+UseSerialGC -XX:-CompactStrings -XX:-UseSuperWord

1 tests failed.
FAILED:  org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([50B30A56B62EDA61:4C90724C2D713174]:0)
	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
	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:804)




Build Log:
[...truncated 7429 lines...]
   [junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=50B30A56B62EDA61 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ug-Arab-CN -Dtests.timezone=Pacific/Chatham -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 1.22s J1 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([50B30A56B62EDA61:4C90724C2D713174]:0)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:804)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {s=Lucene50(blocksize=128)}, docValues:{ndv=DocValuesFormat(name=Memory)}, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=ug-Arab-CN, timezone=Pacific/Chatham
   [junit4]   2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=475929512,total=508887040
   [junit4]   2> NOTE: All tests run in this JVM: [IndexSortingTest, TestFieldCacheReopen, TestHighFreqTerms, TestNumericTerms32, TestIndexSplitter, TestPKIndexSplitter, TestSortingMergePolicy]
   [junit4] Completed [18/25 (1!)] on J1 in 5.16s, 4 tests, 1 failure <<< FAILURES!

[...truncated 34 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/lucene/build.xml:475: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:2187: 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:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1014: There were test failures: 25 suites, 191 tests, 1 failure, 1 ignored (1 assumption) [seed: 50B30A56B62EDA61]

Total time: 18 minutes 0 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 (32bit/jdk-9-ea+102) - Build # 15743 - Still Failing!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15743/
Java: 32bit/jdk-9-ea+102 -server -XX:+UseSerialGC -XX:-CompactStrings -XX:-UseSuperWord

1 tests failed.
FAILED:  org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([29A209E10B59C18C:358171FB90062A99]:0)
	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
	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:804)




Build Log:
[...truncated 7399 lines...]
   [junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=29A209E10B59C18C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nus-SD -Dtests.timezone=Australia/Lord_Howe -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 1.62s J1 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([29A209E10B59C18C:358171FB90062A99]:0)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:804)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {s=PostingsFormat(name=Memory doPackFST= false)}, docValues:{ndv=DocValuesFormat(name=Direct)}, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=nus-SD, timezone=Australia/Lord_Howe
   [junit4]   2> NOTE: Linux 3.19.0-47-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=42029528,total=64880640
   [junit4]   2> NOTE: All tests run in this JVM: [TestIndexSplitter, TestSortingMergePolicy]
   [junit4] Completed [7/25 (1!)] on J1 in 8.33s, 4 tests, 1 failure <<< FAILURES!

[...truncated 67 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/lucene/build.xml:475: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:2187: 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:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1014: There were test failures: 25 suites, 191 tests, 1 failure, 1 ignored (1 assumption) [seed: 29A209E10B59C18C]

Total time: 18 minutes 52 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] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_72) - Build # 15742 - Still Failing!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15742/
Java: 32bit/jdk1.8.0_72 -client -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([83C8D59CD8D0BC8F:9FEBAD86438F579A]:0)
	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at 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:745)




Build Log:
[...truncated 28 lines...]
ERROR: Error fetching remote repo 'origin'
hudson.plugins.git.GitException: Failed to fetch from git://git.apache.org/lucene-solr.git
	at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:766)
	at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1022)
	at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1053)
	at hudson.scm.SCM.checkout(SCM.java:485)
	at hudson.model.AbstractProject.checkout(AbstractProject.java:1276)
	at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:607)
	at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
	at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:529)
	at hudson.model.Run.execute(Run.java:1738)
	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
	at hudson.model.ResourceController.execute(ResourceController.java:98)
	at hudson.model.Executor.run(Executor.java:410)
Caused by: hudson.plugins.git.GitException: org.eclipse.jgit.api.errors.TransportException: Connection reset
	at org.jenkinsci.plugins.gitclient.JGitAPIImpl$2.execute(JGitAPIImpl.java:635)
	at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:764)
	... 11 more
Caused by: org.eclipse.jgit.api.errors.TransportException: Connection reset
	at org.eclipse.jgit.api.FetchCommand.call(FetchCommand.java:139)
	at org.jenkinsci.plugins.gitclient.JGitAPIImpl$2.execute(JGitAPIImpl.java:633)
	... 12 more
Caused by: org.eclipse.jgit.errors.TransportException: Connection reset
	at org.eclipse.jgit.transport.BasePackConnection.readAdvertisedRefs(BasePackConnection.java:182)
	at org.eclipse.jgit.transport.TransportGitAnon$TcpFetchConnection.<init>(TransportGitAnon.java:194)
	at org.eclipse.jgit.transport.TransportGitAnon.openFetch(TransportGitAnon.java:120)
	at org.eclipse.jgit.transport.FetchProcess.executeImp(FetchProcess.java:136)
	at org.eclipse.jgit.transport.FetchProcess.execute(FetchProcess.java:122)
	at org.eclipse.jgit.transport.Transport.fetch(Transport.java:1138)
	at org.eclipse.jgit.api.FetchCommand.call(FetchCommand.java:130)
	... 13 more
Caused by: java.net.SocketException: Connection reset
	at java.net.SocketInputStream.read(SocketInputStream.java:196)
	at java.net.SocketInputStream.read(SocketInputStream.java:122)
	at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)
	at java.io.BufferedInputStream.read1(BufferedInputStream.java:275)
	at java.io.BufferedInputStream.read(BufferedInputStream.java:334)
	at org.eclipse.jgit.util.IO.readFully(IO.java:246)
	at org.eclipse.jgit.transport.PacketLineIn.readLength(PacketLineIn.java:186)
	at org.eclipse.jgit.transport.PacketLineIn.readString(PacketLineIn.java:138)
	at org.eclipse.jgit.transport.BasePackConnection.readAdvertisedRefsImpl(BasePackConnection.java:195)
	at org.eclipse.jgit.transport.BasePackConnection.readAdvertisedRefs(BasePackConnection.java:176)
	... 19 more
ERROR: null
Retrying after 10 seconds
Fetching changes from the remote Git repository
Cleaning workspace
Checking out Revision c136bd7dc737096843ff538e172762026b895f5b (refs/remotes/origin/master)
No emails were triggered.
[description-setter] Description set: Java: 32bit/jdk1.8.0_72 -client -XX:+UseSerialGC
[Lucene-Solr-trunk-Linux] $ /var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/bin/ant "-Dargs=-client -XX:+UseSerialGC" jenkins-hourly
Buildfile: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml

jenkins-hourly:

-print-java-info:
[java-info] java version "1.8.0_72"
[java-info] Java(TM) SE Runtime Environment (1.8.0_72-b15, Oracle Corporation)
[java-info] Java HotSpot(TM) Server VM (25.72-b15, Oracle Corporation)
[java-info] Test args: [-client -XX:+UseSerialGC]

clean:

clean:

clean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: Apache Ivy 2.3.0 - 20130110142753 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] 	confs: [default]
[ivy:cachepath] 	found org.codehaus.groovy#groovy-all;2.4.4 in public
[ivy:cachepath] :: resolution report :: resolve 95ms :: artifacts dl 2ms
	---------------------------------------------------------------------
	|                  |            modules            ||   artifacts   |
	|       conf       | number| search|dwnlded|evicted|| number|dwnlded|
	---------------------------------------------------------------------
	|      default     |   1   |   0   |   0   |   0   ||   1   |   0   |
	---------------------------------------------------------------------

-test-with-heapdumps-enabled:
     [echo] Java HotSpot(TM) Server VM: Enabling heap dumps on OutOfMemoryError to dir '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/heapdumps'.
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/heapdumps

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve-groovy:

test:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

-clover.load:

resolve-groovy:

-init-totals:

test-core:

-clover.disable:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

-clover.load:

-clover.classpath:

-clover.setup:

clover:

-check-git-state:

-git-cleanroot:

-copy-git-state:
     [copy] Copying 1 file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build

git-autoclean:

resolve:

init:

compile-core:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java
    [javac] Compiling 746 source files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java
    [javac] Note: Some input files use or override a deprecated API.
    [javac] Note: Recompile with -Xlint:deprecation for details.
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/document/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/util/bkd/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/util/packed/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/util/mutable/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/compressing/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene60/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/perfield/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/util/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/analysis/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/index/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/store/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/blocktree/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/util/fst/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/search/spans/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/analysis/tokenattributes/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene54/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/search/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/search/similarities/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/util/automaton/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene53/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene50/package-info.class
     [copy] Copying 3 files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java

compile-test-framework:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

init:

compile-lucene-core:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

init:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

compile-core:

compile-codecs:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

common.init:

compile-lucene-core:

init:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

compile-core:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java
    [javac] Compiling 68 source files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java/org/apache/lucene/codecs/bloom/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java/org/apache/lucene/codecs/memory/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java/org/apache/lucene/codecs/simpletext/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java/org/apache/lucene/codecs/blockterms/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java/org/apache/lucene/codecs/blocktreeords/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java/org/apache/lucene/codecs/autoprefix/package-info.class
     [copy] Copying 3 files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

common.compile-core:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java
    [javac] Compiling 176 source files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java
    [javac] Note: Some input files use or override a deprecated API.
    [javac] Note: Recompile with -Xlint:deprecation for details.
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/asserting/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/ramonly/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java/org/apache/lucene/mockfile/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/cheapbastard/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/mockrandom/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/cranky/package-info.class
    [javac] Creating empty /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/compressing/dummy/package-info.class
     [copy] Copying 4 files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java

compile-core:

compile-test:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/test
    [javac] Compiling 418 source files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/test
    [javac] Note: Some input files use or override a deprecated API.
    [javac] Note: Recompile with -Xlint:deprecation for details.
     [copy] Copying 3 files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/test

install-junit4-taskdef:

validate:

resolve-groovy:

-init-totals:

-test:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test
[loadresource] Do not set property tests.explicitclass as its length is 0.
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/temp
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/.caches/test-stats/core
   [junit4] <JUnit4> says 今日は! Master seed: E14FB071FA1651BD
   [junit4] Executing 411 suites with 3 JVMs.
   [junit4] 
   [junit4] Started J0 PID(21889@localhost).
   [junit4] Started J2 PID(21892@localhost).
   [junit4] Started J1 PID(21890@localhost).
   [junit4] Suite: org.apache.lucene.TestAssertions
   [junit4] Completed [1/411] on J0 in 0.31s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50SegmentInfoFormat
   [junit4] Completed [2/411] on J2 in 1.30s, 15 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestCrashCausesCorruptIndex
   [junit4] Completed [3/411] on J2 in 0.15s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestLevenshteinAutomata
   [junit4] Completed [4/411] on J1 in 1.87s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestFuzzyQuery
   [junit4] Completed [5/411] on J2 in 0.24s, 10 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BTerms
   [junit4] IGNOR/A 0.00s J2 | Test2BTerms.test2BTerms
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=very slow, use 5g minimum heap))
   [junit4] Completed [6/411] on J2 in 0.01s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestRollback
   [junit4] Completed [7/411] on J2 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.mutable.TestMutableValues
   [junit4] Completed [8/411] on J2 in 0.02s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestComplexExplanationsOfNonMatches
   [junit4] Completed [9/411] on J2 in 0.14s, 21 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanTermQuery
   [junit4] Completed [10/411] on J2 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestTopFieldCollector
   [junit4] Completed [11/411] on J1 in 0.58s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestBufferedIndexInput
   [junit4] Completed [12/411] on J2 in 0.79s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestSimpleFSDirectory
   [junit4] Completed [13/411] on J1 in 1.41s, 43 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestWindowsMMap
   [junit4] Completed [14/411] on J1 in 0.19s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestStressIndexing
   [junit4] Completed [15/411] on J1 in 3.39s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSegmentInfos
   [junit4] Completed [16/411] on J1 in 0.03s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocsAndPositions
   [junit4] Completed [17/411] on J1 in 0.45s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanFirstQuery
   [junit4] Completed [18/411] on J1 in 0.06s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNoMergeScheduler
   [junit4] Completed [19/411] on J1 in 0.02s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMatchAllDocsQuery
   [junit4] Completed [20/411] on J1 in 0.03s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestBinaryDocValuesUpdates
   [junit4] IGNOR/A 0.01s J0 | TestBinaryDocValuesUpdates.testTonsOfUpdates
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [21/411] on J0 in 8.87s, 28 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSynonymQuery
   [junit4] Completed [22/411] on J0 in 0.08s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestFilterDirectoryReader
   [junit4] Completed [23/411] on J0 in 0.04s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestIntArrayDocIdSet
   [junit4] Completed [24/411] on J0 in 1.04s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestSimpleFSLockFactory
   [junit4] IGNOR/A 0.00s J2 | TestSimpleFSLockFactory.testDeleteLockFile
   [junit4]    > Assumption #1: test requires the ability to delete a locked file(throwable: java.io.IOException: cannot delete file: test.lock, a virus scanner has it open)
   [junit4] Completed [25/411] on J2 in 7.68s, 7 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.compressing.TestFastDecompressionMode
   [junit4] Completed [26/411] on J2 in 3.18s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestByteSlices
   [junit4] Completed [27/411] on J1 in 6.50s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBooleanQuery
   [junit4] Completed [28/411] on J1 in 0.44s, 22 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.TestSearch
   [junit4] Completed [29/411] on J1 in 0.04s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSegmentTermEnum
   [junit4] Completed [30/411] on J1 in 0.04s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexInput
   [junit4] Completed [31/411] on J2 in 1.45s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestBlockPostingsFormat3
   [junit4] Completed [32/411] on J1 in 1.59s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterFromReader
   [junit4] Completed [33/411] on J1 in 0.57s, 12 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestCodecHoldsOpenFiles
   [junit4] Completed [34/411] on J1 in 0.10s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestAutomatonQueryUnicode
   [junit4] Completed [35/411] on J1 in 0.05s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.TestSearchForDuplicates
   [junit4] Completed [36/411] on J1 in 0.14s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiPhraseEnum
   [junit4] Completed [37/411] on J1 in 0.04s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSearchWithThreads
   [junit4] Completed [38/411] on J2 in 5.68s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestManyFields
   [junit4] Completed [39/411] on J2 in 1.00s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterOnJRECrash
   [junit4] IGNOR/A 0.00s J2 | TestIndexWriterOnJRECrash.testNRTThreads
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [40/411] on J2 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestShardSearching
   [junit4] Completed [41/411] on J0 in 12.97s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTermsEnum2
   [junit4] Completed [42/411] on J2 in 1.88s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestMinimize
   [junit4] Completed [43/411] on J0 in 2.26s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.fst.TestFSTs
   [junit4] IGNOR/A 0.01s J1 | TestFSTs.testBigSet
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [44/411] on J1 in 8.37s, 20 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanContainQuery
   [junit4] Completed [45/411] on J1 in 0.05s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexReaderClose
   [junit4] Completed [46/411] on J1 in 0.09s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanOrQuery
   [junit4] Completed [47/411] on J1 in 0.01s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestArrayUtil
   [junit4] Completed [48/411] on J2 in 2.77s, 13 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestPriorityQueue
   [junit4] Completed [49/411] on J1 in 0.91s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestFilterLeafReader
   [junit4] Completed [50/411] on J1 in 0.04s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.compressing.TestFastCompressionMode
   [junit4] Completed [51/411] on J2 in 1.69s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestFixedBitSet
   [junit4] Completed [52/411] on J0 in 4.94s, 19 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMultiTermsEnum
   [junit4] Completed [53/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestStressAdvance
   [junit4] Completed [54/411] on J1 in 3.41s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestOmitTf
   [junit4] Completed [55/411] on J1 in 0.33s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestExitableDirectoryReader
   [junit4] Completed [56/411] on J1 in 0.43s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestBlockPostingsFormat
   [junit4] Completed [57/411] on J2 in 6.81s, 26 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50CompoundFormat
   [junit4] Completed [58/411] on J0 in 7.33s, 24 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestTimSorter
   [junit4] Completed [59/411] on J0 in 0.04s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestExceedMaxTermLength
   [junit4] Completed [60/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestBytesRefHash
   [junit4] Completed [61/411] on J2 in 2.74s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestMergedIterator
   [junit4] Completed [62/411] on J2 in 0.21s, 25 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestDocValuesScoring
   [junit4] Completed [63/411] on J2 in 0.08s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMultiDocValues
   [junit4] Completed [64/411] on J0 in 0.40s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTragicIndexWriterDeadlock
   [junit4] Completed [65/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50StoredFieldsFormatHighCompression
   [junit4] IGNOR/A 0.00s J1 | TestLucene50StoredFieldsFormatHighCompression.testBigDocuments
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [66/411] on J1 in 7.69s, 19 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestQueryRescorer
   [junit4] Completed [67/411] on J0 in 0.62s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test4GBStoredFields
   [junit4] IGNOR/A 0.00s J1 | Test4GBStoredFields.test
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [68/411] on J1 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestTermRangeQuery
   [junit4] Completed [69/411] on J0 in 0.10s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestRateLimiter
   [junit4] Completed [70/411] on J0 in 0.46s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestCrash
   [junit4] Completed [71/411] on J0 in 0.49s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiTermConstantScore
   [junit4] Completed [72/411] on J0 in 0.90s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSortedNumericSortField
   [junit4] Completed [73/411] on J0 in 0.07s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNoDeletionPolicy
   [junit4] Completed [74/411] on J0 in 0.25s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiCollector
   [junit4] Completed [75/411] on J0 in 0.33s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocValuesIndexing
   [junit4] Completed [76/411] on J0 in 0.18s, 34 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterConfig
   [junit4] Completed [77/411] on J0 in 0.03s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestForTooMuchCloning
   [junit4] Completed [78/411] on J0 in 0.04s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPositionIncrement
   [junit4] Completed [79/411] on J0 in 0.03s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTermdocPerf
   [junit4] Completed [80/411] on J0 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterCommit
   [junit4] IGNOR/A 0.00s J0 | TestIndexWriterCommit.testCommitOnCloseDiskUsage
   [junit4]    > Assumption #1: This test cannot run with Memory codec
   [junit4] Completed [81/411] on J0 in 0.85s, 13 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestBytesRefArray
   [junit4] Completed [82/411] on J0 in 0.04s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMaxPosition
   [junit4] Completed [83/411] on J0 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestRamUsageEstimator
   [junit4] IGNOR/A 0.00s J0 | TestRamUsageEstimator.testPrintValues
   [junit4]    > Assumption #1: Specify -Dtests.verbose=true to print constants of RamUsageEstimator.
   [junit4] IGNOR/A 0.00s J0 | TestRamUsageEstimator.testHotspotBean
   [junit4]    > Assumption #1: testHotspotBean only works on 64bit JVMs.
   [junit4] Completed [84/411] on J0 in 0.02s, 5 tests, 2 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterLockRelease
   [junit4] Completed [85/411] on J0 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterOnVMError
   [junit4] IGNOR/A 0.00s J0 | TestIndexWriterOnVMError.testCheckpoint
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [86/411] on J0 in 0.54s, 3 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestParallelTermEnum
   [junit4] Completed [87/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestInfoStream
   [junit4] Completed [88/411] on J0 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestBagOfPositions
   [junit4] Completed [89/411] on J2 in 5.52s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.TestExternalCodecs
   [junit4] Completed [90/411] on J2 in 0.30s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDirectoryReader
   [junit4] Completed [91/411] on J2 in 0.75s, 24 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPhraseQuery
   [junit4] Completed [92/411] on J0 in 1.42s, 21 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestCompiledAutomaton
   [junit4] Completed [93/411] on J2 in 0.35s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestFieldReuse
   [junit4] Completed [94/411] on J2 in 0.03s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestLSBRadixSorter
   [junit4] Completed [95/411] on J2 in 0.08s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestStressIndexing2
   [junit4] Completed [96/411] on J2 in 0.35s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestDateSort
   [junit4] Completed [97/411] on J2 in 0.06s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSloppyPhraseQuery
   [junit4] Completed [98/411] on J2 in 0.82s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestSloppyMath
   [junit4] Completed [99/411] on J2 in 0.16s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestDeterminizeLexicon
   [junit4] Completed [100/411] on J0 in 2.21s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestSingleInstanceLockFactory
   [junit4] Completed [101/411] on J0 in 1.16s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestConstantScoreQuery
   [junit4] Completed [102/411] on J0 in 0.04s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSwappedIndexFiles
   [junit4] Completed [103/411] on J0 in 0.17s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiValuedNumericRangeQuery
   [junit4] Completed [104/411] on J0 in 1.15s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.compressing.TestHighCompressionMode
   [junit4] Completed [105/411] on J0 in 2.29s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestMmapDirectory
   [junit4] Completed [106/411] on J0 in 0.94s, 43 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.document.TestFieldType
   [junit4] Completed [107/411] on J0 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMultiFields
   [junit4] Completed [108/411] on J0 in 0.88s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTwoPhaseCommitTool
   [junit4] Completed [109/411] on J0 in 0.02s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestTimeLimitingCollector
   [junit4] Completed [110/411] on J0 in 2.12s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSortRandom
   [junit4] Completed [111/411] on J0 in 0.43s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestUnicodeUtil
   [junit4] Completed [112/411] on J0 in 0.65s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestEarlyTermination
   [junit4] Completed [113/411] on J0 in 0.05s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNRTThreads
   [junit4] Completed [114/411] on J2 in 11.17s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBooleanOr
   [junit4] Completed [115/411] on J2 in 2.54s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterDelete
   [junit4] IGNOR/A 0.00s J0 | TestIndexWriterDelete.testApplyDeletesOnFlush
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [116/411] on J0 in 3.28s, 27 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterForceMerge
   [junit4] Completed [117/411] on J0 in 1.01s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50StoredFieldsFormat
   [junit4] IGNOR/A 0.00s J0 | TestLucene50StoredFieldsFormat.testBigDocuments
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [118/411] on J0 in 2.31s, 17 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSizeBoundedForceMerge
   [junit4] Completed [119/411] on J0 in 0.08s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterMerging
   [junit4] Completed [120/411] on J2 in 3.58s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestLockFactory
   [junit4] Completed [121/411] on J0 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestNearSpansOrdered
   [junit4] Completed [122/411] on J2 in 0.19s, 18 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestParallelReaderEmptyIndex
   [junit4] Completed [123/411] on J2 in 0.03s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestDocIdSetBuilder
   [junit4] Completed [124/411] on J2 in 0.88s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPrefixCodedTerms
   [junit4] Completed [125/411] on J2 in 0.35s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterOnDiskFull
   [junit4] IGNOR/A 0.00s J2 | TestIndexWriterOnDiskFull.testAddIndexOnDiskFull
   [junit4]    > Assumption #1: This test cannot run with Memory codec
   [junit4] Completed [126/411] on J2 in 0.18s, 4 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestSmallFloat
   [junit4] Completed [127/411] on J2 in 0.16s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMaxTermFrequency
   [junit4] Completed [128/411] on J2 in 0.16s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestOperations
   [junit4] Completed [129/411] on J2 in 1.54s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestReaderClosed
   [junit4] Completed [130/411] on J2 in 0.04s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestConsistentFieldNumbers
   [junit4] Completed [131/411] on J2 in 3.52s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiTermQueryRewrites
   [junit4] Completed [132/411] on J2 in 0.04s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestQueryBuilder
   [junit4] Completed [133/411] on J2 in 0.03s, 20 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestTrackingDirectoryWrapper
   [junit4] IGNOR/A 0.00s J2 | TestTrackingDirectoryWrapper.testFsyncDoesntCreateNewFiles
   [junit4]    > Assumption #1: test only works for FSDirectory subclasses
   [junit4] Completed [134/411] on J2 in 1.43s, 48 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBooleanScorer
   [junit4] Completed [135/411] on J2 in 2.58s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestNumericRangeQuery64
   [junit4] Completed [136/411] on J0 in 11.58s, 29 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestWildcardRandom
   [junit4] Completed [137/411] on J2 in 0.28s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTermVectorsWriter
   [junit4] Completed [138/411] on J0 in 0.22s, 15 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestParallelCompositeReader
   [junit4] Completed [139/411] on J0 in 0.33s, 16 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPositiveScoresOnlyCollector
   [junit4] Completed [140/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestDisjunctionMaxQuery
   [junit4] Completed [141/411] on J0 in 0.26s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestPagedBytes
   [junit4] Completed [142/411] on J0 in 5.44s, 4 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNumericDocValuesUpdates
   [junit4] IGNOR/A 0.00s J2 | TestNumericDocValuesUpdates.testTonsOfUpdates
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [143/411] on J2 in 7.88s, 29 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestAllFilesHaveChecksumFooter
   [junit4] Completed [144/411] on J2 in 0.45s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTransactions
   [junit4] Completed [145/411] on J2 in 2.19s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.document.TestDateTools
   [junit4] Completed [146/411] on J2 in 0.02s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSimpleSearchEquivalence
   [junit4] Completed [147/411] on J2 in 0.79s, 17 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.TestMergeSchedulerExternal
   [junit4] Completed [148/411] on J2 in 0.40s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.tokenattributes.TestSimpleAttributeImpl
   [junit4] Completed [149/411] on J2 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriter
   [junit4] Completed [150/411] on J0 in 6.94s, 71 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.LimitedFiniteStringsIteratorTest
   [junit4] Completed [151/411] on J0 in 0.15s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterMaxDocs
   [junit4] IGNOR/A 0.00s J2 | TestIndexWriterMaxDocs.testExactlyAtTrueLimit
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=takes over two hours))
   [junit4] Completed [152/411] on J2 in 1.81s, 21 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestComplexExplanations
   [junit4] Completed [153/411] on J2 in 0.67s, 21 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestNamedSPILoader
   [junit4] Completed [154/411] on J2 in 0.03s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.fst.TestBytesStore
   [junit4] Completed [155/411] on J2 in 1.47s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.similarities.TestBM25Similarity
   [junit4] Completed [156/411] on J2 in 0.01s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanExplanationsOfNonMatches
   [junit4] Completed [157/411] on J2 in 0.07s, 30 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNeverDelete
   [junit4] Completed [158/411] on J0 in 4.28s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestUniqueTermCount
   [junit4] Completed [159/411] on J0 in 0.04s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTransactionRollback
   [junit4] Completed [160/411] on J0 in 0.13s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBooleanCoord
   [junit4] Completed [161/411] on J0 in 0.12s, 76 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestVersion
   [junit4] Completed [162/411] on J0 in 0.02s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestSentinelIntSet
   [junit4] Completed [163/411] on J0 in 0.16s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestDocValuesRewriteMethod
   [junit4] Completed [164/411] on J2 in 3.43s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIsCurrent
   [junit4] Completed [165/411] on J2 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterReader
   [junit4] IGNOR/A 0.00s J0 | TestIndexWriterReader.testDuringAddIndexes
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [166/411] on J0 in 4.97s, 23 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat
   [junit4] Completed [167/411] on J1 in 62.67s, 96 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTryDelete
   [junit4] Completed [168/411] on J1 in 0.03s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestFlushByRamOrCountsPolicy
   [junit4] Completed [169/411] on J1 in 1.97s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.document.TestDocument
   [junit4] Completed [170/411] on J1 in 0.07s, 12 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMinShouldMatch2
   [junit4] Completed [171/411] on J2 in 11.31s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanBoostQuery
   [junit4] Completed [172/411] on J2 in 0.01s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestRollingUpdates
   [junit4] Completed [173/411] on J2 in 1.27s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
   [junit4] IGNOR/A 0.01s J2 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
   [junit4]    > Assumption #1: aborting test: timeout obtaining lock
   [junit4] Completed [174/411] on J2 in 1.41s, 12 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPrefixQuery
   [junit4] Completed [175/411] on J1 in 6.05s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BBinaryDocValues
   [junit4] IGNOR/A 0.00s J1 | Test2BBinaryDocValues.testFixedBinary
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=takes ~ 6 hours if the heap is 5gb))
   [junit4] IGNOR/A 0.00s J1 | Test2BBinaryDocValues.testVariableBinary
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=takes ~ 6 hours if the heap is 5gb))
   [junit4] Completed [176/411] on J1 in 0.00s, 2 tests, 2 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.tokenattributes.TestBytesRefAttImpl
   [junit4] Completed [177/411] on J1 in 0.03s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestFileSwitchDirectory
   [junit4] IGNOR/A 0.01s J1 | TestFileSwitchDirectory.testFsyncDoesntCreateNewFiles
   [junit4]    > Assumption #1: test only works for FSDirectory subclasses
   [junit4] Completed [178/411] on J1 in 1.32s, 44 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestOmitNorms
   [junit4] Completed [179/411] on J1 in 0.92s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestNumericRangeQuery32
   [junit4] Completed [180/411] on J2 in 3.72s, 23 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.packed.TestPackedInts
   [junit4] IGNOR/A 0.00s J0 | TestPackedInts.testBlockReaderOverflow
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [181/411] on J0 in 14.98s, 28 tests, 3 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestAddIndexes
   [junit4] Completed [182/411] on J2 in 2.03s, 23 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestMultiMMap
   [junit4] Completed [183/411] on J2 in 2.25s, 55 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSearcherManager
   [junit4] Completed [184/411] on J2 in 7.33s, 10 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterExceptions
   [junit4] IGNOR/A 0.00s J0 | TestIndexWriterExceptions.testTooManyTokens
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [185/411] on J0 in 13.53s, 36 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDeletionPolicy
   [junit4] Completed [186/411] on J2 in 4.11s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestBasics
   [junit4] Completed [187/411] on J0 in 1.71s, 25 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterMergePolicy
   [junit4] Completed [188/411] on J2 in 1.88s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBoolean2
   [junit4] Completed [189/411] on J0 in 3.23s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPersistentSnapshotDeletionPolicy
   [junit4] Completed [190/411] on J2 in 1.31s, 13 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNorms
   [junit4] Completed [191/411] on J2 in 2.97s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestAtomicUpdate
   [junit4] Completed [192/411] on J0 in 4.68s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDirectoryReaderReopen
   [junit4] Completed [193/411] on J2 in 1.56s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSearchAfter
   [junit4] Completed [194/411] on J0 in 0.71s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestConcurrentMergeScheduler
   [junit4] Completed [195/411] on J2 in 2.21s, 16 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestTopDocsMerge
   [junit4] Completed [196/411] on J2 in 2.51s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestLongPostings
   [junit4] Completed [197/411] on J2 in 3.15s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocumentsWriterStallControl
   [junit4] Completed [198/411] on J0 in 7.69s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNRTReaderWithThreads
   [junit4] Completed [199/411] on J0 in 1.34s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTieredMergePolicy
   [junit4] Completed [200/411] on J0 in 3.51s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterUnicode
   [junit4] Completed [201/411] on J2 in 6.15s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.perfield.TestPerFieldPostingsFormat
   [junit4] IGNOR/A 0.00s J0 | TestPerFieldPostingsFormat.testPostingsEnumReuse
   [junit4]    > Assumption #1: The MockRandom PF randomizes content on the fly, so we can't check it
   [junit4] IGNOR/A 0.00s J0 | TestPerFieldPostingsFormat.testMergeStability
   [junit4]    > Assumption #1: The MockRandom PF randomizes content on the fly, so we can't check it
   [junit4] Completed [202/411] on J0 in 17.82s, 24 tests, 2 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestWeakIdentityMap
   [junit4] Completed [203/411] on J0 in 8.23s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSimpleExplanations
   [junit4] Completed [204/411] on J0 in 1.24s, 68 tests
   [junit4] 
   [junit4] HEARTBEAT J1 PID(21890@localhost): 2016-02-02T10:37:43, stalled for 67.0s at: TestTimSorterWorstCase.testWorstCaseStackSize
   [junit4] Suite: org.apache.lucene.index.TestSegmentMerger
   [junit4] Completed [205/411] on J0 in 0.69s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestTimSorterWorstCase
   [junit4] Completed [206/411] on J1 in 68.57s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBooleanQueryVisitSubscorers
   [junit4] Completed [207/411] on J1 in 0.05s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestRegexpRandom2
   [junit4] Completed [208/411] on J0 in 2.33s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestNRTCachingDirectory
   [junit4] IGNOR/A 0.00s J0 | TestNRTCachingDirectory.testFsyncDoesntCreateNewFiles
   [junit4]    > Assumption #1: test only works for FSDirectory subclasses
   [junit4] Completed [209/411] on J0 in 1.40s, 45 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSegmentReader
   [junit4] Completed [210/411] on J0 in 0.72s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterNRTIsCurrent
   [junit4] Completed [211/411] on J0 in 2.76s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiThreadTermVectors
   [junit4] Completed [212/411] on J0 in 1.40s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSegmentTermDocs
   [junit4] Completed [213/411] on J0 in 0.41s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSort
   [junit4] Completed [214/411] on J0 in 0.13s, 25 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.TestToken
   [junit4] Completed [215/411] on J0 in 0.02s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestFieldsReader
   [junit4] Completed [216/411] on J0 in 0.38s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestCollectionUtil
   [junit4] Completed [217/411] on J1 in 9.93s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.TestCharFilter
   [junit4] Completed [218/411] on J1 in 0.01s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestByteArrayDataInput
   [junit4] Completed [219/411] on J1 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestCustomSearcherSort
   [junit4] Completed [220/411] on J0 in 1.54s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.similarities.TestSimilarityBase
   [junit4] Completed [221/411] on J0 in 1.19s, 32 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestFieldCacheRewriteMethod
   [junit4] Completed [222/411] on J1 in 3.40s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestCharsRefBuilder
   [junit4] Completed [223/411] on J1 in 0.01s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestRegexpRandom
   [junit4] Completed [224/411] on J1 in 0.16s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestHugeRamFile
   [junit4] Completed [225/411] on J0 in 1.59s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSnapshotDeletionPolicy
   [junit4] Completed [226/411] on J1 in 1.20s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestParallelLeafReader
   [junit4] Completed [227/411] on J1 in 0.09s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPayloads
   [junit4] Completed [228/411] on J1 in 0.14s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.perfield.TestPerFieldPostingsFormat2
   [junit4] Completed [229/411] on J1 in 0.59s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBooleanMinShouldMatch
   [junit4] Completed [230/411] on J0 in 4.01s, 17 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestBagOfPostings
   [junit4] Completed [231/411] on J1 in 2.66s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPointValues
   [junit4] Completed [232/411] on J1 in 0.13s, 23 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMergeRateLimiter
   [junit4] Completed [233/411] on J1 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BSortedDocValuesFixedSorted
   [junit4] IGNOR/A 0.00s J1 | Test2BSortedDocValuesFixedSorted.testFixedSorted
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=Takes ~ 6 hours if the heap is 5gb))
   [junit4] Completed [234/411] on J1 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMatchNoDocsQuery
   [junit4] Completed [235/411] on J1 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpans
   [junit4] Completed [236/411] on J0 in 0.53s, 26 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestNIOFSDirectory
   [junit4] Completed [237/411] on J1 in 1.14s, 43 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSimpleExplanationsOfNonMatches
   [junit4] Completed [238/411] on J1 in 0.16s, 68 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPhrasePrefixQuery
   [junit4] Completed [239/411] on J1 in 0.04s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestDirectory
   [junit4] Completed [240/411] on J1 in 0.39s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestCustomNorms
   [junit4] Completed [241/411] on J0 in 1.67s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPostingsOffsets
   [junit4] Completed [242/411] on J0 in 1.86s, 13 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMixedCodecs
   [junit4] Completed [243/411] on J0 in 1.72s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestScorerPerf
   [junit4] Completed [244/411] on J1 in 10.19s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestFrequencyTrackingRingBuffer
   [junit4] Completed [245/411] on J1 in 0.30s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestCodecUtil
   [junit4] Completed [246/411] on J1 in 0.02s, 15 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestAllFilesHaveCodecHeader
   [junit4] Completed [247/411] on J1 in 0.56s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestLegacyNumericUtils
   [junit4] Completed [248/411] on J1 in 5.66s, 12 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestConjunctions
   [junit4] Completed [249/411] on J1 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestUTF32ToUTF8
   [junit4] Completed [250/411] on J0 in 16.21s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanExplanations
   [junit4] Completed [251/411] on J0 in 0.45s, 30 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestRAMDirectory
   [junit4] IGNOR/A 0.00s J0 | TestRAMDirectory.testFsyncDoesntCreateNewFiles
   [junit4]    > Assumption #1: test only works for FSDirectory subclasses
   [junit4] Completed [252/411] on J0 in 1.49s, 47 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiPhraseQuery
   [junit4] Completed [253/411] on J0 in 0.15s, 17 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestLazyProxSkipping
   [junit4] Completed [254/411] on J0 in 0.23s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestDeterminism
   [junit4] Completed [255/411] on J0 in 0.79s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestCodecs
   [junit4] Completed [256/411] on J0 in 0.36s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.tokenattributes.TestCharTermAttributeImpl
   [junit4] Completed [257/411] on J0 in 0.53s, 12 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestOmitPositions
   [junit4] Completed [258/411] on J0 in 0.23s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestThreadedForceMerge
   [junit4] Completed [259/411] on J0 in 0.40s, 1 test
   [junit4] 
   [junit4] HEARTBEAT J2 PID(21892@localhost): 2016-02-02T10:38:31, stalled for 69.2s at: TestTermsEnum.testVaryingTermsPerSegment
   [junit4] Suite: org.apache.lucene.index.TestDuelingCodecs
   [junit4] Completed [260/411] on J1 in 11.45s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestFlex
   [junit4] Completed [261/411] on J1 in 0.16s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestStressNRT
   [junit4] Completed [262/411] on J0 in 2.32s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestForceMergeForever
   [junit4] Completed [263/411] on J0 in 0.57s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSameScoresWithThreads
   [junit4] Completed [264/411] on J0 in 0.48s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocumentsWriterDeleteQueue
   [junit4] Completed [265/411] on J0 in 0.12s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocumentWriter
   [junit4] Completed [266/411] on J0 in 0.14s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSloppyPhraseQuery2
   [junit4] Completed [267/411] on J1 in 1.87s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestLiveFieldValues
   [junit4] Completed [268/411] on J0 in 2.90s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDoc
   [junit4] Completed [269/411] on J0 in 0.17s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestWildcard
   [junit4] Completed [270/411] on J0 in 0.13s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.similarities.TestSimilarity2
   [junit4] Completed [271/411] on J0 in 0.17s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanSearchEquivalence
   [junit4] Completed [272/411] on J1 in 3.53s, 33 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestFieldMaskingSpanQuery
   [junit4] IGNOR/A 0.00s J0 | TestFieldMaskingSpanQuery.testSimple2
   [junit4]    > Assumption #1: Broken scoring: LUCENE-3723
   [junit4] IGNOR/A 0.00s J0 | TestFieldMaskingSpanQuery.testSpans2
   [junit4]    > Assumption #1: Broken scoring: LUCENE-3723
   [junit4] Completed [273/411] on J0 in 0.17s, 11 tests, 2 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestRegexpQuery
   [junit4] Completed [274/411] on J1 in 0.08s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTermVectorsReader
   [junit4] Completed [275/411] on J0 in 0.08s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPerSegmentDeletes
   [junit4] Completed [276/411] on J1 in 0.06s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPrefixInBooleanQuery
   [junit4] Completed [277/411] on J1 in 0.46s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIntBlockPool
   [junit4] Completed [278/411] on J0 in 0.71s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestTopDocsCollector
   [junit4] Completed [279/411] on J1 in 0.05s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestTermScorer
   [junit4] Completed [280/411] on J1 in 0.05s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSumDocFreq
   [junit4] Completed [281/411] on J1 in 0.14s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestAutomatonQuery
   [junit4] Completed [282/411] on J0 in 1.41s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexableField
   [junit4] Completed [283/411] on J1 in 1.01s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPrefixRandom
   [junit4] Completed [284/411] on J0 in 0.73s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMultiLevelSkipList
   [junit4] Completed [285/411] on J1 in 0.34s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPayloadsOnVectors
   [junit4] Completed [286/411] on J1 in 0.03s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanMultiTermQueryWrapper
   [junit4] Completed [287/411] on J1 in 0.08s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestCheckIndex
   [junit4] Completed [288/411] on J0 in 0.56s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexFileDeleter
   [junit4] Completed [289/411] on J1 in 0.14s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestElevationComparator
   [junit4] Completed [290/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestBinaryTerms
   [junit4] Completed [291/411] on J1 in 0.05s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSubScorerFreqs
   [junit4] Completed [292/411] on J0 in 0.04s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestRecyclingIntBlockAllocator
   [junit4] Completed [293/411] on J1 in 0.06s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.document.TestField
   [junit4] Completed [294/411] on J0 in 0.03s, 19 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSimilarity
   [junit4] Completed [295/411] on J1 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestVirtualMethod
   [junit4] Completed [296/411] on J0 in 0.01s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSimilarityProvider
   [junit4] Completed [297/411] on J0 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.TestNumericTokenStream
   [junit4] Completed [298/411] on J0 in 0.02s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestRollingBuffer
   [junit4] Completed [299/411] on J1 in 0.52s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestSetOnce
   [junit4] Completed [300/411] on J0 in 0.03s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestFilterIterator
   [junit4] Completed [301/411] on J0 in 0.04s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.TestCachingTokenFilter
   [junit4] Completed [302/411] on J1 in 0.26s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestCharsRef
   [junit4] Completed [303/411] on J0 in 0.03s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BPostings
   [junit4] IGNOR/A 0.00s J1 | Test2BPostings.test
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [304/411] on J1 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSameTokenSamePosition
   [junit4] Completed [305/411] on J0 in 0.03s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocCount
   [junit4] Completed [306/411] on J1 in 0.07s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestCachingCollector
   [junit4] Completed [307/411] on J0 in 0.02s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.document.TestBinaryDocument
   [junit4] Completed [308/411] on J1 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestAttributeSource
   [junit4] Completed [309/411] on J0 in 0.02s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestTotalHitCountCollector
   [junit4] Completed [310/411] on J1 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestRecyclingByteBlockAllocator
   [junit4] Completed [311/411] on J0 in 0.04s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNoMergePolicy
   [junit4] Completed [312/411] on J1 in 0.04s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestByteBlockPool
   [junit4] Completed [313/411] on J0 in 0.06s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestBytesRef
   [junit4] Completed [314/411] on J1 in 0.02s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.TestDemo
   [junit4] Completed [315/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestNot
   [junit4] Completed [316/411] on J1 in 0.03s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestCloseableThreadLocal
   [junit4] Completed [317/411] on J0 in 0.01s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.MultiCollectorTest
   [junit4] Completed [318/411] on J1 in 0.03s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestDocBoost
   [junit4] Completed [319/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestNGramPhraseQuery
   [junit4] Completed [320/411] on J1 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestScoreCachingWrappingScorer
   [junit4] Completed [321/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestIntsRef
   [junit4] Completed [322/411] on J1 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BPositions
   [junit4] IGNOR/A 0.00s J0 | Test2BPositions.test
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=uses lots of space and takes a few minutes))
   [junit4] Completed [323/411] on J0 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexCommit
   [junit4] Completed [324/411] on J1 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTerm
   [junit4] Completed [325/411] on J0 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BPostingsBytes
   [junit4] IGNOR/A 0.00s J1 | Test2BPostingsBytes.test
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=takes ~20GB-30GB of space and 10 minutes))
   [junit4] Completed [326/411] on J1 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestIOUtils
   [junit4] Completed [327/411] on J0 in 0.13s, 18 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNewestSegment
   [junit4] Completed [328/411] on J1 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BNumericDocValues
   [junit4] IGNOR/A 0.00s J0 | Test2BNumericDocValues.testNumerics
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=takes ~ 2 hours if the heap is 5gb))
   [junit4] Completed [329/411] on J0 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.Test2BPagedBytes
   [junit4] IGNOR/A 0.00s J1 | Test2BPagedBytes.test
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=You must increase heap to > 2 G to run this))
   [junit4] Completed [330/411] on J1 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTermsEnum
   [junit4] Completed [331/411] on J2 in 93.23s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.TestReusableStringReader
   [junit4] Completed [333/411] on J1 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.analysis.tokenattributes.TestPackedTokenAttributeImpl
   [junit4] Completed [334/411] on J2 in 0.02s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.TestCodecLoadingDeadlock
   [junit4] Completed [335/411] on J0 in 0.26s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestForUtil
   [junit4] Completed [336/411] on J0 in 0.12s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50FieldInfoFormat
   [junit4] Completed [337/411] on J0 in 0.54s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestBlockPostingsFormat2
   [junit4] Completed [338/411] on J2 in 1.85s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene53.TestLucene53NormsFormat
   [junit4] IGNOR/A 0.00s J2 | TestLucene53NormsFormat.testNCommonBig
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J2 | TestLucene53NormsFormat.testMergeStability
   [junit4]    > Assumption #1: The MockRandom PF randomizes content on the fly, so we can't check it
   [junit4] Completed [339/411] on J2 in 10.56s, 17 tests, 2 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50TermVectorsFormat
   [junit4] Completed [340/411] on J0 in 29.81s, 17 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene60.TestLucene60PointFormat
   [junit4] IGNOR/A 0.00s J0 | TestLucene60PointFormat.testRandomBinaryBig
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J0 | TestLucene60PointFormat.testMergeStability
   [junit4]    > Assumption #1: TODO: mess with the parameters and test gets angry!
   [junit4] Completed [341/411] on J0 in 4.05s, 16 tests, 2 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.Test2BSortedDocValuesOrds
   [junit4] IGNOR/A 0.00s J0 | Test2BSortedDocValuesOrds.test2BOrds
   [junit4]    > Assumption #1: 'monster' test group is disabled (@Monster(value=Takes ~ 6 hours if the heap is 5gb))
   [junit4] Completed [342/411] on J0 in 0.00s, 1 test, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestAllFilesCheckIndexHeader
   [junit4] Completed [343/411] on J0 in 0.25s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestAllFilesDetectTruncation
   [junit4] Completed [344/411] on J0 in 0.24s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDemoParallelLeafReader
   [junit4] Completed [345/411] on J0 in 5.22s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocInverterPerFieldErrorInfo
   [junit4] Completed [346/411] on J0 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDocValues
   [junit4] Completed [347/411] on J0 in 0.03s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestDuelingCodecsAtNight
   [junit4] IGNOR/A 0.00s J0 | TestDuelingCodecsAtNight.testBigEquals
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J0 | TestDuelingCodecsAtNight.testEquals
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J0 | TestDuelingCodecsAtNight.testCrazyReaderEquals
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [348/411] on J0 in 0.00s, 3 tests, 3 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterDeleteByQuery
   [junit4] Completed [349/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterExceptions2
   [junit4] Completed [350/411] on J0 in 5.23s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterOutOfFileDescriptors
   [junit4] Completed [351/411] on J0 in 2.23s, 1 test
   [junit4] 
   [junit4] HEARTBEAT J1 PID(21890@localhost): 2016-02-02T10:39:55, stalled for 65.8s at: TestGrowableByteArrayDataOutput.testWriteLargeStrings
   [junit4] Suite: org.apache.lucene.index.TestIndexWriterThreadsToSegments
   [junit4] Completed [352/411] on J0 in 18.39s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestLogMergePolicy
   [junit4] Completed [353/411] on J0 in 0.03s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMixedDocValuesUpdates
   [junit4] IGNOR/A 0.00s J0 | TestMixedDocValuesUpdates.testTonsOfUpdates
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [354/411] on J0 in 5.96s, 4 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestNRTReaderCleanup
   [junit4] Completed [355/411] on J0 in 0.03s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestOrdinalMap
   [junit4] Completed [356/411] on J0 in 0.10s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestReadOnlyIndex
   [junit4] Completed [357/411] on J0 in 0.16s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestReaderWrapperDVTypeCheck
   [junit4] Completed [358/411] on J0 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestStressDeletes
   [junit4] Completed [359/411] on J0 in 1.13s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTermVectors
   [junit4] Completed [360/411] on J0 in 0.39s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestTerms
   [junit4] Completed [361/411] on J0 in 0.37s, 8 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestUpgradeIndexMergePolicy
   [junit4] Completed [362/411] on J0 in 0.04s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.FuzzyTermOnShortTermsTest
   [junit4] Completed [363/411] on J0 in 0.06s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestApproximationSearchEquivalence
   [junit4] Completed [364/411] on J0 in 0.64s, 10 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBlendedTermQuery
   [junit4] Completed [365/411] on J0 in 0.02s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBooleanRewrites
   [junit4] Completed [366/411] on J0 in 0.21s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestBoostQuery
   [junit4] Completed [367/411] on J0 in 0.01s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestConjunctionDISI
   [junit4] Completed [368/411] on J0 in 10.30s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestControlledRealTimeReopenThread
   [junit4] IGNOR/A 0.00s J0 | TestControlledRealTimeReopenThread.testCRTReopen
   [junit4]    > Assumption #1: 'awaitsfix' test group is disabled (@AwaitsFix(bugUrl=https://issues.apache.org/jira/browse/LUCENE-5737))
   [junit4] Completed [369/411] on J0 in 5.24s, 5 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestFieldValueQuery
   [junit4] Completed [370/411] on J0 in 3.32s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestIndexSearcher
   [junit4] Completed [371/411] on J0 in 0.14s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestLRUQueryCache
   [junit4] Completed [372/411] on J0 in 6.06s, 17 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestMultiset
   [junit4] Completed [373/411] on J0 in 0.02s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestNeedsScores
   [junit4] Completed [374/411] on J0 in 0.05s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestPointQueries
   [junit4] IGNOR/A 0.00s J0 | TestPointQueries.testRandomLongsBig
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J0 | TestPointQueries.testRandomBinaryBig
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [375/411] on J0 in 15.76s, 19 tests, 2 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestQueryCachingPolicy
   [junit4] Completed [376/411] on J0 in 0.04s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestReqExclBulkScorer
   [junit4] Completed [377/411] on J0 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSortRescorer
   [junit4] Completed [378/411] on J0 in 0.71s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSortedSetSelector
   [junit4] Completed [379/411] on J0 in 0.09s, 15 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestSortedSetSortField
   [junit4] Completed [380/411] on J0 in 0.05s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestUsageTrackingFilterCachingPolicy
   [junit4] Completed [381/411] on J0 in 0.01s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.similarities.TestClassicSimilarity
   [junit4] Completed [382/411] on J0 in 0.09s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestFilterSpans
   [junit4] Completed [383/411] on J0 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanCollection
   [junit4] Completed [384/411] on J0 in 0.03s, 3 tests
   [junit4] 
   [junit4] HEARTBEAT J1 PID(21890@localhost): 2016-02-02T10:40:55, stalled for  126s at: TestGrowableByteArrayDataOutput.testWriteLargeStrings
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanNearQuery
   [junit4] Completed [385/411] on J0 in 0.02s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpanNotQuery
   [junit4] Completed [386/411] on J0 in 0.02s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.spans.TestSpansEnum
   [junit4] Completed [387/411] on J0 in 0.09s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestBufferedChecksum
   [junit4] Completed [388/411] on J0 in 0.07s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestFilterDirectory
   [junit4] IGNOR/A 0.00s J0 | TestFilterDirectory.testFsyncDoesntCreateNewFiles
   [junit4]    > Assumption #1: test only works for FSDirectory subclasses
   [junit4] Completed [389/411] on J0 in 1.57s, 45 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestNativeFSLockFactory
   [junit4] IGNOR/A 0.00s J0 | TestNativeFSLockFactory.testDeleteLockFile
   [junit4]    > Assumption #1: test requires the ability to delete a locked file(throwable: java.io.IOException: cannot delete file: test.lock, a virus scanner has it open)
   [junit4] Completed [390/411] on J0 in 8.75s, 10 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestSleepingLockWrapper
   [junit4] Completed [391/411] on J0 in 7.30s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestFixedBitDocIdSet
   [junit4] Completed [392/411] on J0 in 1.51s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestInPlaceMergeSorter
   [junit4] Completed [393/411] on J0 in 0.04s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestIntroSorter
   [junit4] Completed [394/411] on J0 in 0.04s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestLongBitSet
   [junit4] IGNOR/A 0.00s J0 | TestLongBitSet.testHugeCapacity
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [395/411] on J0 in 0.38s, 9 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestMathUtil
   [junit4] Completed [396/411] on J0 in 0.03s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestNotDocIdSet
   [junit4] Completed [397/411] on J0 in 3.44s, 6 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestOfflineSorter
   [junit4] IGNOR/A 0.00s J0 | TestOfflineSorter.testLargerRandom
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [398/411] on J0 in 9.50s, 7 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestRoaringDocIdSet
   [junit4] Completed [399/411] on J0 in 0.98s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestSPIClassIterator
   [junit4] Completed [400/411] on J0 in 0.01s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestSparseFixedBitDocIdSet
   [junit4] Completed [401/411] on J0 in 2.91s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestSparseFixedBitSet
   [junit4] Completed [402/411] on J0 in 4.37s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.TestStringHelper
   [junit4] Completed [403/411] on J0 in 0.02s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.FiniteStringsIteratorTest
   [junit4] Completed [404/411] on J0 in 0.28s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.compressing.TestGrowableByteArrayDataOutput
   [junit4] Completed [405/411] on J1 in 172.59s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestRegExp
   [junit4] Completed [406/411] on J1 in 0.10s, 5 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.automaton.TestAutomaton
   [junit4] Completed [407/411] on J0 in 4.78s, 56 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.bkd.TestBKD
   [junit4] IGNOR/A 0.00s J1 | TestBKD.testRandomBinaryBig
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [408/411] on J1 in 6.76s, 15 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.packed.TestDirectPacked
   [junit4] Completed [409/411] on J1 in 1.29s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.packed.TestDirectMonotonic
   [junit4] Completed [410/411] on J0 in 5.92s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.codecs.lucene54.TestLucene54DocValuesFormat
   [junit4] IGNOR/A 0.00s J2 | TestLucene54DocValuesFormat.testSortedVariableLengthManyVsStoredFields
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J2 | TestLucene54DocValuesFormat.testTermsEnumRandomMany
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J2 | TestLucene54DocValuesFormat.testSortedSetVariableLengthManyVsStoredFields
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] Completed [411/411] on J2 in 182.23s, 109 tests, 3 skipped
   [junit4] 
   [junit4] JVM J0:     0.37 ..   428.26 =   427.89s
   [junit4] JVM J1:     0.37 ..   427.94 =   427.57s
   [junit4] JVM J2:     0.38 ..   441.55 =   441.17s
   [junit4] Execution time total: 7 minutes 21 seconds
   [junit4] Tests summary: 411 suites (1 ignored), 3365 tests, 60 ignored (56 assumptions)
   [junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J0 (/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J0)
   [junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J1 (/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J1)
   [junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J2 (/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J2)
     [echo] 5 slowest tests:
[junit4:tophints] 182.23s | org.apache.lucene.codecs.lucene54.TestLucene54DocValuesFormat
[junit4:tophints] 172.59s | org.apache.lucene.codecs.compressing.TestGrowableByteArrayDataOutput
[junit4:tophints]  93.23s | org.apache.lucene.index.TestTermsEnum
[junit4:tophints]  68.57s | org.apache.lucene.util.TestTimSorterWorstCase
[junit4:tophints]  62.67s | org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat

-check-totals:

common.test:

test-lock-factory:
[lockVerifySetup] Configuration properties:
[lockVerifySetup]  lockverify.delay=1
[lockVerifySetup]  lockverify.count=1500
[testLockFactory] Testing org.apache.lucene.store.NativeFSLockFactory...
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/lockfactorytest/native
[lockVerifyServer] Listening on /127.0.0.1:33610...
[lockStressTest2] Connecting to server /127.0.0.1:33610 and registering as client 2...
[lockVerifyServer] All clients started, fire gun...
[lockStressTest1] Connecting to server /127.0.0.1:33610 and registering as client 1...
[lockStressTest1] 0.0% done.
[lockStressTest2] 0.0% done.
[lockStressTest1] 33.333333333333336% done.
[lockStressTest2] 33.333333333333336% done.
[lockStressTest2] 66.66666666666667% done.
[lockStressTest1] 66.66666666666667% done.
[lockStressTest2] Finished 1500 tries.
[lockVerifyServer] Server terminated.
[lockStressTest1] Finished 1500 tries.
[testLockFactory] Testing org.apache.lucene.store.SimpleFSLockFactory...
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/lockfactorytest/simple
[lockVerifyServer] Listening on /127.0.0.1:43825...
[lockVerifyServer] All clients started, fire gun...
[lockStressTest2] Connecting to server /127.0.0.1:43825 and registering as client 2...
[lockStressTest1] Connecting to server /127.0.0.1:43825 and registering as client 1...
[lockStressTest2] 0.0% done.
[lockStressTest1] 0.0% done.
[lockStressTest2] 33.333333333333336% done.
[lockStressTest1] 33.333333333333336% done.
[lockStressTest2] 66.66666666666667% done.
[lockStressTest1] 66.66666666666667% done.
[lockStressTest2] Finished 1500 tries.
[lockVerifyServer] Server terminated.
[lockStressTest1] Finished 1500 tries.

test:

test-test-framework:

-clover.disable:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

-clover.load:

-clover.classpath:

-clover.setup:

clover:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

resolve:

init:

compile-lucene-core:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

init:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

compile-core:

compile-codecs:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

common.init:

compile-lucene-core:

init:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

compile-core:

common.compile-core:

compile-core:

compile-test-framework:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

init:

compile-lucene-core:

compile-codecs:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

common.compile-core:

compile-core:

compile-test:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/test
    [javac] Compiling 43 source files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/test
    [javac] Note: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/src/test/org/apache/lucene/analysis/TestGraphTokenizers.java uses or overrides a deprecated API.
    [javac] Note: Recompile with -Xlint:deprecation for details.

install-junit4-taskdef:

validate:

resolve-groovy:

-init-totals:

-test:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-frame

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

y-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

init:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

compile-core:

init:

test:

-clover.disable:

ivy-availability-check:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

-clover.load:

-clover.classpath:

-clover.setup:

clover:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

resolve:

common.init:

compile-lucene-core:

init:

compile-test:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

common.init:

compile-lucene-core:

init:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

compile-core:

compile-test-framework:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

init:

compile-lucene-core:

compile-codecs:

-check-git-state:

-git-cleanroot:

-copy-git-state:

git-autoclean:

ivy-availability-check:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/ivy-settings.xml

resolve:

common.init:

compile-lucene-core:

init:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

compile-core:

-clover.disable:

-clover.load:

-clover.classpath:

-clover.setup:

clover:

common.compile-core:

compile-core:

common.compile-test:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/classes/test
    [javac] Compiling 26 source files to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/classes/test
    [javac] Note: Some input files use or override a deprecated API.
    [javac] Note: Recompile with -Xlint:deprecation for details.

install-junit4-taskdef:

validate:

resolve-groovy:

-init-totals:

-test:
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/test
[loadresource] Do not set property tests.explicitclass as its length is 0.
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/test/temp
    [mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/.caches/test-stats/misc
   [junit4] <JUnit4> says salut! Master seed: 83C8D59CD8D0BC8F
   [junit4] Executing 25 suites with 3 JVMs.
   [junit4] 
   [junit4] Started J0 PID(2615@localhost).
   [junit4] Started J1 PID(2614@localhost).
   [junit4] Started J2 PID(2613@localhost).
   [junit4] Suite: org.apache.lucene.index.TestBlockJoinSorter
   [junit4] Completed [1/25] on J0 in 0.91s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.IndexSortingTest
   [junit4] Completed [2/25] on J1 in 1.01s, 10 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.SortingLeafReaderTest
   [junit4] Completed [3/25] on J2 in 1.05s, 11 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestUninvertingReader
   [junit4] Completed [4/25] on J0 in 0.68s, 7 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestDocTermOrds
   [junit4] Completed [5/25] on J2 in 0.72s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestMultiPassIndexSplitter
   [junit4] Completed [6/25] on J2 in 0.14s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.store.TestRAFDirectory
   [junit4] Completed [7/25] on J1 in 0.95s, 43 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestFieldCache
   [junit4] Completed [8/25] on J0 in 1.10s, 10 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.misc.SweetSpotSimilarityTest
   [junit4] Completed [9/25] on J0 in 0.04s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestIndexSplitter
   [junit4] Completed [10/25] on J1 in 0.68s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestFieldCacheSortRandom
   [junit4] Completed [11/25] on J1 in 0.55s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.util.fst.TestFSTsMisc
   [junit4] Completed [12/25] on J1 in 4.30s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestPKIndexSplitter
   [junit4] Completed [13/25] on J1 in 0.18s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.misc.TestHighFreqTerms
   [junit4] Completed [14/25] on J1 in 0.09s, 9 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.document.TestLazyDocument
   [junit4] Completed [15/25] on J1 in 1.39s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=83C8D59CD8D0BC8F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=he-IL -Dtests.timezone=America/Cayman -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.57s J1 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([83C8D59CD8D0BC8F:9FEBAD86438F579A]:0)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {s=FST50}, docValues:{ndv=DocValuesFormat(name=Lucene54)}, sim=ClassicSimilarity, locale=he-IL, timezone=America/Cayman
   [junit4]   2> NOTE: Linux 3.19.0-47-generic i386/Oracle Corporation 1.8.0_72 (32-bit)/cpus=12,threads=1,free=20871632,total=48570368
   [junit4]   2> NOTE: All tests run in this JVM: [IndexSortingTest, TestRAFDirectory, TestIndexSplitter, TestFieldCacheSortRandom, TestFSTsMisc, TestPKIndexSplitter, TestHighFreqTerms, TestLazyDocument, TestSortingMergePolicy]
   [junit4] Completed [16/25 (1!)] on J1 in 2.61s, 4 tests, 1 failure <<< FAILURES!
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestDiversifiedTopDocsCollector
   [junit4] Completed [17/25 (1!)] on J1 in 0.38s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestFieldCacheReopen
   [junit4] Completed [18/25 (1!)] on J1 in 0.02s, 1 test
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestFieldCacheVsDocValues
   [junit4] IGNOR/A 0.01s J2 | TestFieldCacheVsDocValues.testHugeBinaryValueLimit
   [junit4]    > Assumption #1: test requires codec with limits on max binary field length
   [junit4] Completed [19/25 (1!)] on J2 in 11.53s, 10 tests, 1 skipped
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestFieldCacheSanityChecker
   [junit4] Completed [20/25 (1!)] on J1 in 0.17s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestFieldCacheWithThreads
   [junit4] Completed [21/25 (1!)] on J1 in 0.08s, 2 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestFieldCacheSort
   [junit4] Completed [22/25 (1!)] on J2 in 0.37s, 43 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestNumericTerms32
   [junit4] Completed [23/25 (1!)] on J1 in 4.93s, 3 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.search.TestEarlyTerminatingSortingCollector
   [junit4] Completed [24/25 (1!)] on J0 in 20.54s, 4 tests
   [junit4] 
   [junit4] Suite: org.apache.lucene.uninverting.TestNumericTerms64
   [junit4] Completed [25/25 (1!)] on J2 in 11.77s, 4 tests
   [junit4] 
   [junit4] 
   [junit4] Tests with failures [seed: 83C8D59CD8D0BC8F]:
   [junit4]   - org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded
   [junit4] 
   [junit4] 
   [junit4] JVM J0:     0.29 ..    23.94 =    23.65s
   [junit4] JVM J1:     0.29 ..    19.50 =    19.22s
   [junit4] JVM J2:     0.29 ..    26.70 =    26.41s
   [junit4] Execution time total: 26 seconds
   [junit4] Tests summary: 25 suites, 191 tests, 1 failure, 1 ignored (1 assumption)

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/lucene/build.xml:475: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:2187: 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:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1014: There were test failures: 25 suites, 191 tests, 1 failure, 1 ignored (1 assumption) [seed: 83C8D59CD8D0BC8F]

Total time: 20 minutes 13 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] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_72) - Build # 15741 - Still Failing!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15741/
Java: 32bit/jdk1.8.0_72 -server -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([BE0A4F0968365D58:A2293713F369B64D]:0)
	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at 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:745)




Build Log:
[...truncated 7459 lines...]
   [junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=BE0A4F0968365D58 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=et-EE -Dtests.timezone=SystemV/PST8 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 1.52s J0 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([BE0A4F0968365D58:A2293713F369B64D]:0)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:44)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1917)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1750)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1707)
   [junit4]    > 	at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:63)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {s=FST50}, docValues:{ndv=DocValuesFormat(name=Lucene54)}, sim=ClassicSimilarity, locale=et-EE, timezone=SystemV/PST8
   [junit4]   2> NOTE: Linux 3.19.0-47-generic i386/Oracle Corporation 1.8.0_72 (32-bit)/cpus=12,threads=1,free=130304336,total=214695936
   [junit4]   2> NOTE: All tests run in this JVM: [TestLazyDocument, TestFieldCacheWithThreads, TestBlockJoinSorter, TestUninvertingReader, TestFieldCache, TestIndexSplitter, TestPKIndexSplitter, TestMultiPassIndexSplitter, TestHighFreqTerms, SweetSpotSimilarityTest, TestSortingMergePolicy]
   [junit4] Completed [21/25 (1!)] on J0 in 7.51s, 4 tests, 1 failure <<< FAILURES!

[...truncated 23 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/lucene/build.xml:475: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:2187: 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:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1014: There were test failures: 25 suites, 191 tests, 1 failure, 1 ignored (1 assumption) [seed: BE0A4F0968365D58]

Total time: 20 minutes 28 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



RE: [JENKINS-EA] Lucene-Solr-trunk-Linux (32bit/jdk-9-ea+102) - Build # 15740 - Still Failing!

Posted by Uwe Schindler <uw...@thetaphi.de>.
Oh oh,

same error also with SuperWords disabled!? But this time at another place (also happened here a while ago), maybe 2 different problems?

Uwe

-----
Uwe Schindler
H.-H.-Meier-Allee 63, D-28213 Bremen
http://www.thetaphi.de
eMail: uwe@thetaphi.de


> -----Original Message-----
> From: Policeman Jenkins Server [mailto:jenkins@thetaphi.de]
> Sent: Tuesday, February 02, 2016 9:07 AM
> To: dev@lucene.apache.org
> Subject: [JENKINS-EA] Lucene-Solr-trunk-Linux (32bit/jdk-9-ea+102) - Build #
> 15740 - Still Failing!
> Importance: Low
> 
> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15740/
> Java: 32bit/jdk-9-ea+102 -server -XX:+UseG1GC -XX:-CompactStrings -XX:-
> UseSuperWord
> 
> All tests passed
> 
> Build Log:
> [...truncated 396 lines...]
>    [junit4] JVM J2: stdout was not empty, see:
> /home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.sysout
>    [junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
>    [junit4] #
>    [junit4] # A fatal error has been detected by the Java Runtime
> Environment:
>    [junit4] #
>    [junit4] #  SIGSEGV (0xb) at pc=0xf7249300, pid=31605, tid=31729
>    [junit4] #
>    [junit4] # JRE version: Java(TM) SE Runtime Environment (9.0+102) (build 9-
> ea+102-2016-01-21-001243.javare.4316.nc)
>    [junit4] # Java VM: Java HotSpot(TM) Server VM (9-ea+102-2016-01-21-
> 001243.javare.4316.nc, mixed mode, tiered, g1 gc, linux-x86)
>    [junit4] # Problematic frame:
>    [junit4] # V  [libjvm.so+0x9b3300]  Type::cmp(Type const*, Type
> const*)+0x10
>    [junit4] #
>    [junit4] # No core dump will be written. Core dumps have been disabled. To
> enable core dumping, try "ulimit -c unlimited" before starting Java again
>    [junit4] #
>    [junit4] # An error report file with more information is saved as:
>    [junit4] # /home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/J2/hs_err_pid31605.log
>    [junit4] #
>    [junit4] # Compiler replay data is saved as:
>    [junit4] # /home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/J2/replay_pid31605.log
>    [junit4] #
>    [junit4] # If you would like to submit a bug report, please visit:
>    [junit4] #   http://bugreport.java.com/bugreport/crash.jsp
>    [junit4] #
>    [junit4] <<< JVM J2: EOF ----
> 
> [...truncated 990 lines...]
>    [junit4] ERROR: JVM J2 ended with an exception, command line:
> /home/jenkins/tools/java/32bit/jdk-9-ea+102/bin/java -server -
> XX:+UseG1GC -XX:-CompactStrings -XX:-UseSuperWord -
> XX:+HeapDumpOnOutOfMemoryError -
> XX:HeapDumpPath=/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/heapdumps -ea -esa -Dtests.prefix=tests -
> Dtests.seed=19C3334016770730 -Xmx512M -Dtests.iters= -
> Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -
> Dtests.postingsformat=random -Dtests.docvaluesformat=random -
> Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -
> Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=6.0.0 -
> Dtests.cleanthreads=perMethod -
> Djava.util.logging.config.file=/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/tools/junit4/logging.properties -Dtests.nightly=false -
> Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -
> Dtests.asserts=true -Dtests.multiplier=3 -DtempDir=./temp -
> Djava.io.tmpdir=./temp -
> Djunit4.tempDir=/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/temp -
> Dcommon.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene -
> Dclover.db.dir=/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/clover/db -
> Djava.security.policy=/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/tools/junit4/tests.policy -Dtests.LUCENE_VERSION=6.0.0 -
> Djetty.testMode=1 -Djetty.insecurerandom=1 -
> Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -
> Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -
> Djunit4.childvm.cwd=/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/J2 -Djunit4.childvm.id=2 -
> Djunit4.childvm.count=3 -Dtests.leaveTemporary=false -
> Dtests.filterstacks=true -Dtests.disableHdfs=true -
> Djava.security.manager=org.apache.lucene.util.TestSecurityManager -
> Dfile.encoding=ISO-8859-1 -classpath /home/jenkins/workspace/Lucene-
> Solr-trunk-
> Linux/lucene/build/codecs/classes/java:/home/jenkins/workspace/Lucene-
> Solr-trunk-Linux/lucene/build/test-
> framework/classes/java:/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/test-framework/lib/junit-
> 4.10.jar:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-
> framework/lib/randomizedtesting-runner-
> 2.3.2.jar:/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/classes/java:/home/jenkins/workspace/Lucene-
> Solr-trunk-
> Linux/lucene/build/core/classes/test:/var/lib/jenkins/tools/hudson.tasks.An
> t_AntInstallation/ANT_1.8.2/lib/ant-
> launcher.jar:/var/lib/jenkins/.ant/lib/ivy-
> 2.3.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/
> lib/ant-
> jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.
> 8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_
> 1.8.2/lib/ant-apache-
> bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/l
> ib/ant-
> jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/li
> b/ant-
> junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> /lib/ant-apache-
> xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.
> 2/lib/ant-
> javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.
> 8.2/lib/ant-
> jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib
> /ant-apache-
> bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/li
> b/ant-commons-
> logging.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.
> 2/lib/ant-commons-
> net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/li
> b/ant-apache-
> resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.
> 8.2/lib/ant-apache-
> log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/
> lib/ant-
> junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/
> lib/ant-apache-
> oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/li
> b/ant-
> antlr.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/
> lib/ant-
> jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/li
> b/ant-apache-
> regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.
> 2/lib/ant-
> swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> /lib/ant-
> testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.
> 2/lib/ant-
> netrexx.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtestin
> g/junit4-ant/jars/junit4-ant-2.3.2.jar
> com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile
> /home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.events
> @/home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.suites -
> stdin
>    [junit4] ERROR: JVM J2 ended with an exception: Forked process returned
> with error code: 134. Very likely a JVM crash.  See process stdout at:
> /home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.sysout
>    [junit4] 	at
> com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1481)
>    [junit4] 	at
> com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
>    [junit4] 	at
> com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
>    [junit4] 	at
> com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
>    [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
>    [junit4] 	at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.jav
> a:1158)
>    [junit4] 	at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.ja
> va:632)
>    [junit4] 	at java.lang.Thread.run(Thread.java:804)
> 
> 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/lucene/build.xml:50:
> The following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-
> build.xml:1457: The following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-
> build.xml:1014: At least one slave process threw an exception, first: Forked
> process returned with error code: 134. Very likely a JVM crash.  See process
> stdout at: /home/jenkins/workspace/Lucene-Solr-trunk-
> Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.sysout
> 
> Total time: 9 minutes 18 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
> 



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org


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

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/15740/
Java: 32bit/jdk-9-ea+102 -server -XX:+UseG1GC -XX:-CompactStrings -XX:-UseSuperWord

All tests passed

Build Log:
[...truncated 396 lines...]
   [junit4] JVM J2: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.sysout
   [junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
   [junit4] #
   [junit4] # A fatal error has been detected by the Java Runtime Environment:
   [junit4] #
   [junit4] #  SIGSEGV (0xb) at pc=0xf7249300, pid=31605, tid=31729
   [junit4] #
   [junit4] # JRE version: Java(TM) SE Runtime Environment (9.0+102) (build 9-ea+102-2016-01-21-001243.javare.4316.nc)
   [junit4] # Java VM: Java HotSpot(TM) Server VM (9-ea+102-2016-01-21-001243.javare.4316.nc, mixed mode, tiered, g1 gc, linux-x86)
   [junit4] # Problematic frame:
   [junit4] # V  [libjvm.so+0x9b3300]  Type::cmp(Type const*, Type const*)+0x10
   [junit4] #
   [junit4] # No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
   [junit4] #
   [junit4] # An error report file with more information is saved as:
   [junit4] # /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J2/hs_err_pid31605.log
   [junit4] #
   [junit4] # Compiler replay data is saved as:
   [junit4] # /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J2/replay_pid31605.log
   [junit4] #
   [junit4] # If you would like to submit a bug report, please visit:
   [junit4] #   http://bugreport.java.com/bugreport/crash.jsp
   [junit4] #
   [junit4] <<< JVM J2: EOF ----

[...truncated 990 lines...]
   [junit4] ERROR: JVM J2 ended with an exception, command line: /home/jenkins/tools/java/32bit/jdk-9-ea+102/bin/java -server -XX:+UseG1GC -XX:-CompactStrings -XX:-UseSuperWord -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/heapdumps -ea -esa -Dtests.prefix=tests -Dtests.seed=19C3334016770730 -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=6.0.0 -Dtests.cleanthreads=perMethod -Djava.util.logging.config.file=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=3 -DtempDir=./temp -Djava.io.tmpdir=./temp -Djunit4.tempDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/temp -Dcommon.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene -Dclover.db.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/clover/db -Djava.security.policy=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/tools/junit4/tests.policy -Dtests.LUCENE_VERSION=6.0.0 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Djunit4.childvm.cwd=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/J2 -Djunit4.childvm.id=2 -Djunit4.childvm.count=3 -Dtests.leaveTemporary=false -Dtests.filterstacks=true -Dtests.disableHdfs=true -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Dfile.encoding=ISO-8859-1 -classpath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.3.2.jar:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/test:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.3.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.2.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.events @/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.suites -stdin
   [junit4] ERROR: JVM J2 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash.  See process stdout at: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.sysout
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1481)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
   [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1158)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
   [junit4] 	at java.lang.Thread.run(Thread.java:804)

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/lucene/build.xml:50: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1014: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash.  See process stdout at: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/test/temp/junit4-J2-20160202_075817_353.sysout

Total time: 9 minutes 18 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+102) - Build # 15739 - Still Failing!

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

1 tests failed.
FAILED:  org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test

Error Message:
Exactly one shard should have changed, instead: [shard2, shard1] nodes=([core_node3(shard2), core_node2(shard1), core_node4(shard1)]) expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: Exactly one shard should have changed, instead: [shard2, shard1] nodes=([core_node3(shard2), core_node2(shard1), core_node4(shard1)]) expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([51F9A916A3CF0BF1:D9AD96CC0D336609]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test(DistribDocExpirationUpdateProcessorTest.java:118)
	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:804)




Build Log:
[...truncated 11622 lines...]
   [junit4] Suite: org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/init-core-data-001
   [junit4]   2> 1250068 INFO  (SUITE-DistribDocExpirationUpdateProcessorTest-seed#[51F9A916A3CF0BF1]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1250068 INFO  (SUITE-DistribDocExpirationUpdateProcessorTest-seed#[51F9A916A3CF0BF1]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /cz/ki
   [junit4]   2> 1250070 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1250070 INFO  (Thread-3917) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1250070 INFO  (Thread-3917) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1250170 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkTestServer start zk server on port:44299
   [junit4]   2> 1250171 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1250171 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1250173 INFO  (zkCallback-1318-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1fd225c9 name:ZooKeeperConnection Watcher:127.0.0.1:44299 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1250173 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1250173 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1250173 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1250174 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1250175 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1250175 INFO  (zkCallback-1319-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5cef2594 name:ZooKeeperConnection Watcher:127.0.0.1:44299/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1250175 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1250175 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1250175 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1250176 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1250176 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1250177 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1250177 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-doc-expire-update-processor.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1250177 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1250178 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
   [junit4]   2> 1250178 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1250178 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1250179 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1250179 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1250179 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1250180 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1250180 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1250180 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1250181 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1250181 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1250181 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1250181 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1250182 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1250182 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1250182 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1250183 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1250183 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1250183 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1250183 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1250229 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1
   [junit4]   2> 1250230 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1250231 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@78ff3816{/cz/ki,null,AVAILABLE}
   [junit4]   2> 1250235 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.ServerConnector Started ServerConnector@55330b67{HTTP/1.1}{127.0.0.1:51130}
   [junit4]   2> 1250235 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.Server Started @1251925ms
   [junit4]   2> 1250235 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/tempDir-001/control/data, hostContext=/cz/ki, hostPort=51130, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores}
   [junit4]   2> 1250235 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7fbe847c
   [junit4]   2> 1250235 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001'
   [junit4]   2> 1250236 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1250236 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1250236 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1250237 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1250237 INFO  (zkCallback-1320-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46130dec name:ZooKeeperConnection Watcher:127.0.0.1:44299/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1250237 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1250238 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1250238 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1250238 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/solr.xml
   [junit4]   2> 1250242 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores
   [junit4]   2> 1250242 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer New CoreContainer 83317646
   [junit4]   2> 1250242 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001]
   [junit4]   2> 1250243 WARN  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/lib
   [junit4]   2> 1250243 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] 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> 1250244 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1250245 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1250245 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1250245 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44299/solr
   [junit4]   2> 1250245 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1250245 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1250245 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1250246 INFO  (zkCallback-1323-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58f2f171 name:ZooKeeperConnection Watcher:127.0.0.1:44299 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1250246 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1250246 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1250247 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1250247 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66b361f6 name:ZooKeeperConnection Watcher:127.0.0.1:44299/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1250247 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1250248 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1250249 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1250249 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1250250 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1250250 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1250251 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1250251 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1250251 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1250252 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1250252 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51130_cz%2Fki
   [junit4]   2> 1250252 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51130_cz%2Fki
   [junit4]   2> 1250253 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1250253 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1250253 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1250253 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000
   [junit4]   2> 1250253 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51130_cz%2Fki
   [junit4]   2> 1250254 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1250254 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.Overseer Overseer (id=95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) starting
   [junit4]   2> 1250254 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1250260 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1250261 INFO  (OverseerCollectionConfigSetProcessor-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 1250261 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1250261 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1250267 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1250267 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1250268 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores
   [junit4]   2> 1250268 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 1250268 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1
   [junit4]   2> 1250268 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1250269 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 1250269 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1250269 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1250269 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1250270 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51130/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:51130_cz%2Fki",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1250270 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1250270 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51130/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:51130_cz%2Fki",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1250270 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1250270 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1250270 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1251270 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1251270 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1251270 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1251270 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 1251270 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1251270 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1'
   [junit4]   2> 1251271 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1251271 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1251271 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1251275 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1251276 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1251280 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1251283 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1251352 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1251352 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1251353 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1251354 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1251359 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 1251359 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1251359 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1251360 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1/data/]
   [junit4]   2> 1251360 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
   [junit4]   2> 1251360 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1/data
   [junit4]   2> 1251360 INFO  (coreLoadExecutor-3941-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/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1251360 WARN  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1251360 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1/data/index
   [junit4]   2> 1251360 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1251361 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@b9a029d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67462b3c),segFN=segments_1,generation=1}
   [junit4]   2> 1251361 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1251361 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/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1/data/
   [junit4]   2> 1251361 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1251362 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-defaults"
   [junit4]   2> 1251362 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-field"
   [junit4]   2> 1251362 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-param"
   [junit4]   2> 1251362 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
   [junit4]   2> 1251362 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "scheduled-delete" (default)
   [junit4]   2> 1251362 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
   [junit4]   2> 1251363 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1251363 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1251364 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1251364 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1251365 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1251365 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1251365 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1251365 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1251366 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1251366 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1251366 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1251366 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@b9a029d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67462b3c),segFN=segments_1,generation=1}
   [junit4]   2> 1251366 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1251366 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@750217eb[collection1] main]
   [junit4]   2> 1251366 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1251367 INFO  (searcherExecutor-3942-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@750217eb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1251367 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1525044288790986752
   [junit4]   2> 1251369 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1525044288790986752
   [junit4]   2> 1251369 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1251369 INFO  (coreLoadExecutor-3941-thread-1) [    ] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1251370 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:51130/cz/ki collection:control_collection shard:shard1
   [junit4]   2> 1251370 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 1251371 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/95315267964502020-core_node1-n_0000000000
   [junit4]   2> 1251371 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
   [junit4]   2> 1251371 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1251371 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1251372 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51130/cz/ki/collection1/
   [junit4]   2> 1251372 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1251372 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 1251372 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.SyncStrategy http://127.0.0.1:51130/cz/ki/collection1/ has no replicas
   [junit4]   2> 1251372 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1251373 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 1251373 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51130/cz/ki/collection1/ shard1
   [junit4]   2> 1251373 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:51130/cz/ki",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1251474 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1251524 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ZkController We are http://127.0.0.1:51130/cz/ki/collection1/ and leader is http://127.0.0.1:51130/cz/ki/collection1/
   [junit4]   2> 1251524 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51130/cz/ki
   [junit4]   2> 1251524 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1251524 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1251524 INFO  (coreZkRegister-3935-thread-1) [    ] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1251525 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51130/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:51130_cz%2Fki",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 1251525 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:51130/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:51130_cz%2Fki",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1251626 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1251770 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1251770 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1251771 INFO  (zkCallback-1326-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@55b691b7 name:ZooKeeperConnection Watcher:127.0.0.1:44299/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1251771 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1251771 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1251771 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1251772 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1251818 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1
   [junit4]   2> 1251819 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001
   [junit4]   2> 1251819 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1251820 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@23a70e47{/cz/ki,null,AVAILABLE}
   [junit4]   2> 1251820 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.ServerConnector Started ServerConnector@5fa33beb{HTTP/1.1}{127.0.0.1:35098}
   [junit4]   2> 1251820 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.Server Started @1253510ms
   [junit4]   2> 1251820 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/cz/ki, hostPort=35098, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores}
   [junit4]   2> 1251820 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7fbe847c
   [junit4]   2> 1251820 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001'
   [junit4]   2> 1251821 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1251821 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1251821 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1251821 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1251822 INFO  (zkCallback-1327-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18158c60 name:ZooKeeperConnection Watcher:127.0.0.1:44299/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1251822 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1251822 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1251822 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1251822 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/solr.xml
   [junit4]   2> 1251825 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores
   [junit4]   2> 1251826 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer New CoreContainer 1672995652
   [junit4]   2> 1251826 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001]
   [junit4]   2> 1251826 WARN  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/lib
   [junit4]   2> 1251826 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] 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> 1251826 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1251827 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1251827 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1251827 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44299/solr
   [junit4]   2> 1251827 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1251827 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1251827 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1251828 INFO  (zkCallback-1330-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4f10aef0 name:ZooKeeperConnection Watcher:127.0.0.1:44299 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1251828 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1251828 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1251829 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1251829 INFO  (zkCallback-1331-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@71d69bf2 name:ZooKeeperConnection Watcher:127.0.0.1:44299/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1251829 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1251831 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1252832 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35098_cz%2Fki
   [junit4]   2> 1252833 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:35098_cz%2Fki
   [junit4]   2> 1252833 INFO  (zkCallback-1326-thread-1) [    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1252833 INFO  (zkCallback-1331-thread-1) [    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1252833 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1252835 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1252835 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95315267964502024-127.0.0.1:35098_cz%2Fki-n_0000000001
   [junit4]   2> 1252835 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000 to know if I could be the leader
   [junit4]   2> 1252839 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1252839 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1252839 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores
   [junit4]   2> 1252840 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 1252840 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1
   [junit4]   2> 1252840 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1252840 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 1252841 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1252841 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1252841 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1252841 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1252841 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:35098/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:35098_cz%2Fki",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 3
   [junit4]   2> 1252841 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:35098/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:35098_cz%2Fki",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1252841 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1252842 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 1252943 INFO  (zkCallback-1326-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1252943 INFO  (zkCallback-1331-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1252943 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1253841 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1253841 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 1253842 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1253842 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1]
   [junit4]   2> 1253842 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1253842 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1'
   [junit4]   2> 1253842 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1253842 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1253843 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1253846 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1253848 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1253852 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1253855 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1253896 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1253896 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1253897 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1253898 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1253903 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 1253903 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 1253903 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1253904 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1/data/]
   [junit4]   2> 1253904 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
   [junit4]   2> 1253904 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1/data
   [junit4]   2> 1253904 INFO  (coreLoadExecutor-3953-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/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1/data/index/
   [junit4]   2> 1253904 WARN  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1253904 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1/data/index
   [junit4]   2> 1253904 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1253905 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@3ab9424a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eec6cdb),segFN=segments_1,generation=1}
   [junit4]   2> 1253905 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1253905 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/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1/data/
   [junit4]   2> 1253905 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    ] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-1-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1253906 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-defaults"
   [junit4]   2> 1253906 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-field"
   [junit4]   2> 1253906 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-param"
   [junit4]   2> 1253906 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "convert-ttl-field-with-param-default"
   [junit4]   2> 1253906 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "scheduled-delete" (default)
   [junit4]   2> 1253906 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "scheduled-delete" (default)
   [junit4]   2> 1253907 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1253908 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1253908 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1253908 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1253909 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1253909 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1253909 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1253909 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1253910 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1253910 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1253910 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1253910 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@3ab9424a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@eec6cdb),segFN=segments_1,generation=1}
   [junit4]   2> 1253910 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1253910 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@65316546[collection1] main]
   [junit4]   2> 1253910 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1]
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1253911 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1253912 INFO  (searcherExecutor-3954-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65316546[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1253912 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1253912 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1253912 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1253912 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1525044291459612672
   [junit4]   2> 1253913 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1525044291459612672
   [junit4]   2> 1253914 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1253914 INFO  (coreLoadExecutor-3953-thread-1) [    ] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1253914 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:35098/cz/ki collection:collection1 shard:shard2
   [junit4]   2> 1253914 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leader_elect/shard2/election
   [junit4]   2> 1253915 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/collection1/leader_elect/shard2/election/95315267964502024-core_node1-n_0000000000
   [junit4]   2> 1253915 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard2 and weAreReplacement=false and leaderVoteWait=10000
   [junit4]   2> 1253916 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1253916 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1253916 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35098/cz/ki/collection1/
   [junit4]   2> 1253916 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1253916 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"collection1"} current state version: 4
   [junit4]   2> 1253916 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.SyncStrategy http://127.0.0.1:35098/cz/ki/collection1/ has no replicas
   [junit4]   2> 1253916 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leaders/shard2
   [junit4]   2> 1253917 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 1253917 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:35098/cz/ki/collection1/ shard2
   [junit4]   2> 1253918 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:35098/cz/ki",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 4
   [junit4]   2> 1254018 INFO  (zkCallback-1326-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1254018 INFO  (zkCallback-1331-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1254018 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1254068 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ZkController We are http://127.0.0.1:35098/cz/ki/collection1/ and leader is http://127.0.0.1:35098/cz/ki/collection1/
   [junit4]   2> 1254068 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35098/cz/ki
   [junit4]   2> 1254068 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1254068 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1254068 INFO  (coreZkRegister-3948-thread-1) [    ] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1254069 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:35098/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:35098_cz%2Fki",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 5
   [junit4]   2> 1254069 INFO  (OverseerStateUpdate-95315267964502020-127.0.0.1:51130_cz%2Fki-n_0000000000) [    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:35098/cz/ki",
   [junit4]   2>   "node_name":"127.0.0.1:35098_cz%2Fki",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1254170 INFO  (zkCallback-1326-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1254170 INFO  (zkCallback-1331-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1254170 INFO  (zkCallback-1324-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1254367 INFO  (autoExpireDocs-3947-thread-1) [    ] o.a.s.u.p.DocExpirationUpdateProcessorFactory Begining periodic deletion of expired docs
   [junit4]   2> 1254370 INFO  (autoExpireDocs-3947-thread-1) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1254370 INFO  (autoExpireDocs-3947-thread-1) [    ] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 1254370 INFO  (autoExpireDocs-3947-thread-1) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1254370 INFO  (autoExpireDocs-3947-thread-1) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {deleteByQuery={!cache=false}eXpField_tdt:[* TO 2016-02-02T06:46:12.602Z] (-1525044291937763328),commit=} 0 2
   [junit4]   2> 1254370 INFO  (autoExpireDocs-3947-thread-1) [    ] o.a.s.u.p.DocExpirationUpdateProcessorFactory Finished periodic deletion of expired docs
   [junit4]   2> 1254401 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A

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

e/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-4-001/cores/collection1/data;done=false>>]
   [junit4]   2> 1308174 INFO  (zkCallback-1347-thread-2) [    ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-4-001/cores/collection1/data
   [junit4]   2> 1308174 INFO  (zkCallback-1347-thread-2) [    ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-4-001/cores/collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-4-001/cores/collection1/data/index;done=false>>]
   [junit4]   2> 1308174 INFO  (zkCallback-1347-thread-2) [    ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001/shard-4-001/cores/collection1/data/index
   [junit4]   2> 1308175 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@1ff3161f{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 1308175 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3684a65b{/cz/ki,null,UNAVAILABLE}
   [junit4]   2> 1308176 INFO  (TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[51F9A916A3CF0BF1]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44299 44299
   [junit4]   2> 1308274 INFO  (Thread-3917) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44299 44299
   [junit4]   2> 1308275 WARN  (Thread-3917) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	6	/solr/aliases.json
   [junit4]   2> 	5	/solr/security.json
   [junit4]   2> 	5	/solr/configs/conf1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	6	/solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	6	/solr/live_nodes
   [junit4]   2> 	6	/solr/collections
   [junit4]   2> 	3	/solr/overseer/collection-queue-work
   [junit4]   2> 	2	/solr/overseer/queue
   [junit4]   2> 	2	/solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=DistribDocExpirationUpdateProcessorTest -Dtests.method=test -Dtests.seed=51F9A916A3CF0BF1 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pl-PL -Dtests.timezone=Australia/Lord_Howe -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 58.2s J1 | DistribDocExpirationUpdateProcessorTest.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Exactly one shard should have changed, instead: [shard2, shard1] nodes=([core_node3(shard2), core_node2(shard1), core_node4(shard1)]) expected:<1> but was:<2>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([51F9A916A3CF0BF1:D9AD96CC0D336609]:0)
   [junit4]    > 	at org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test(DistribDocExpirationUpdateProcessorTest.java:118)
   [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:804)
   [junit4]   2> 1308278 INFO  (SUITE-DistribDocExpirationUpdateProcessorTest-seed#[51F9A916A3CF0BF1]-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/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_51F9A916A3CF0BF1-001
   [junit4]   2> Feb 02, 2016 6:47:06 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {_version_=PostingsFormat(name=Asserting), eXpField_tdt=PostingsFormat(name=Asserting), tTl_s=PostingsFormat(name=Asserting), id=FST50}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=pl-PL, timezone=Australia/Lord_Howe
   [junit4]   2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=316254416,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestMiniSolrCloudClusterBase, HdfsWriteToMultipleCollectionsTest, TestDFRSimilarityFactory, HighlighterMaxOffsetTest, ZkCLITest, TestSha256AuthenticationProvider, TestCloudPivotFacet, SolrPluginUtilsTest, TestLMJelinekMercerSimilarityFactory, CoreAdminRequestStatusTest, ChaosMonkeySafeLeaderTest, CSVRequestHandlerTest, RestartWhileUpdatingTest, TestRawTransformer, ConvertedLegacyTest, DocExpirationUpdateProcessorFactoryTest, TimeZoneUtilsTest, TestCloudSchemaless, BadCopyFieldTest, ConnectionManagerTest, TestStressVersions, HdfsSyncSliceTest, TestSortByMinMaxFunction, LeaderInitiatedRecoveryOnShardRestartTest, TestSimpleTrackingShardHandler, ChaosMonkeyNothingIsSafeTest, HttpPartitionTest, TestReloadAndDeleteDocs, TestConfigSets, TestRandomRequestDistribution, BasicFunctionalityTest, TestQuerySenderNoQuery, TestQueryTypes, SolrInfoMBeanTest, TestLRUStatsCache, HLLSerializationTest, TestBulkSchemaAPI, TestCoreContainer, TestLeaderElectionZkExpiry, AsyncMigrateRouteKeyTest, TestOverriddenPrefixQueryForCustomFieldType, HdfsChaosMonkeySafeLeaderTest, DistribCursorPagingTest, TestSchemaManager, NumericFieldsTest, TestBM25SimilarityFactory, TestConfigSetsAPIExclusivity, TestExceedMaxTermLength, SmileWriterTest, TestBlobHandler, MoreLikeThisHandlerTest, SolrIndexSplitterTest, TestUpdate, TestComplexPhraseQParserPlugin, TestSystemIdResolver, TestManagedSchemaFieldTypeResource, TestAnalyzeInfixSuggestions, TestPerFieldSimilarityWithDefaultOverride, ClusterStateUpdateTest, TestRandomForceMergePolicy, TestCursorMarkWithoutUniqueKey, TestEmbeddedSolrServerConstructors, TestSolrCloudWithKerberosAlt, SuggesterFSTTest, DistributedQueueTest, CdcrUpdateLogTest, TestTrie, TestSolrDynamicMBean, TestAuthorizationFramework, SaslZkACLProviderTest, DistributedExpandComponentTest, TestAddFieldRealTimeGet, CloudMLTQParserTest, SolrIndexConfigTest, TestDistribDocBasedVersion, RulesTest, UpdateRequestProcessorFactoryTest, TestDocumentBuilder, SpellCheckComponentTest, OpenCloseCoreStressTest, MBeansHandlerTest, ExitableDirectoryReaderTest, DocValuesMissingTest, TestReqParamsAPI, TestUniqueKeyFieldResource, CollectionsAPIAsyncDistributedZkTest, OpenExchangeRatesOrgProviderTest, DistanceFunctionTest, HighlighterTest, TestStressUserVersions, TestDistributedGrouping, SolrXmlInZkTest, CdcrReplicationHandlerTest, DirectSolrSpellCheckerTest, ShardRoutingTest, TestReplicationHandler, DistributedSpellCheckComponentTest, TestJoin, QueryElevationComponentTest, TestFiltering, IndexSchemaRuntimeFieldTest, TestJmxIntegration, QueryParsingTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, TestSolrIndexConfig, BadComponentTest, TestStressRecovery, TestCodecSupport, TestJmxMonitoredMap, URLClassifyProcessorTest, DateMathParserTest, DateFieldTest, TestLRUCache, ZkNodePropsTest, FileUtilsTest, DistributedMLTComponentTest, TestDistributedMissingSort, TestHighlightDedupGrouping, ConnectionReuseTest, AliasIntegrationTest, CollectionReloadTest, DistribDocExpirationUpdateProcessorTest]
   [junit4] Completed [436/577 (1!)] on J1 in 58.73s, 1 test, 1 failure <<< FAILURES!

[...truncated 456 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:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1014: There were test failures: 577 suites (8 ignored), 2321 tests, 1 failure, 115 ignored (70 assumptions) [seed: 51F9A916A3CF0BF1]

Total time: 49 minutes 59 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