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/22 22:51:19 UTC
[JENKINS-EA] Lucene-Solr-5.5-Linux (64bit/jdk-9-ea+106) - Build #
81 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/81/
Java: 64bit/jdk-9-ea+106 -XX:+UseCompressedOops -XX:+UseG1GC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.lucene.codecs.memory.TestMemoryPostingsFormat
Error Message:
The test or suite printed 11784 bytes to stdout and stderr, even though the limit was set to 8192 bytes. Increase the limit with @Limit, ignore it completely with @SuppressSysoutChecks or run with -Dtests.verbose=true
Stack Trace:
java.lang.AssertionError: The test or suite printed 11784 bytes to stdout and stderr, even though the limit was set to 8192 bytes. Increase the limit with @Limit, ignore it completely with @SuppressSysoutChecks or run with -Dtests.verbose=true
at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
at org.apache.lucene.util.TestRuleLimitSysouts.afterIfSuccessful(TestRuleLimitSysouts.java:211)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterIfSuccessful(TestRuleAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:37)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:804)
FAILED: org.apache.lucene.codecs.bloom.TestBloomPostingsFormat.testDocsAndFreqsAndPositions
Error Message:
Captured an uncaught exception in thread: Thread[id=548, name=Thread-527, state=RUNNABLE, group=TGRP-TestBloomPostingsFormat]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=548, name=Thread-527, state=RUNNABLE, group=TGRP-TestBloomPostingsFormat]
at __randomizedtesting.SeedInfo.seed([F21D8556605F9927:E83E31C798BC9923]:0)
Caused by: java.lang.RuntimeException: java.lang.ArrayIndexOutOfBoundsException: 9
at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 9
at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:235)
at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:524)
at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
FAILED: org.apache.lucene.codecs.memory.TestMemoryPostingsFormat.testDocsAndFreqsAndPositions
Error Message:
Captured an uncaught exception in thread: Thread[id=616, name=Thread-595, state=RUNNABLE, group=TGRP-TestMemoryPostingsFormat]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=616, name=Thread-595, state=RUNNABLE, group=TGRP-TestMemoryPostingsFormat]
Caused by: java.lang.RuntimeException: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
Caused by: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
at org.apache.lucene.util.automaton.Automaton.addTransition(Automaton.java:167)
at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:245)
at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:537)
at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:521)
at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
Build Log:
[...truncated 6314 lines...]
[junit4] Suite: org.apache.lucene.codecs.memory.TestMemoryPostingsFormat
[junit4] 2> ?????? 23, 2016 3:50:17 ? com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[Thread-595,5,TGRP-TestMemoryPostingsFormat]
[junit4] 2> java.lang.RuntimeException: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] 2> at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] 2> Caused by: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] 2> at org.apache.lucene.util.automaton.Automaton.addTransition(Automaton.java:167)
[junit4] 2> at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:245)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:537)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:521)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
[junit4] 2>
[junit4] 2> ?????? 23, 2016 3:50:17 ? com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[Thread-597,5,TGRP-TestMemoryPostingsFormat]
[junit4] 2> java.lang.RuntimeException: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] 2> at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] 2> Caused by: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] 2> at org.apache.lucene.util.automaton.Automaton.addTransition(Automaton.java:167)
[junit4] 2> at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:245)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:537)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:546)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:546)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:519)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
[junit4] 2>
[junit4] 2> ?????? 23, 2016 3:50:17 ? com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[Thread-596,5,TGRP-TestMemoryPostingsFormat]
[junit4] 2> java.lang.RuntimeException: java.lang.ArrayIndexOutOfBoundsException: 5
[junit4] 2> at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] 2> Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
[junit4] 2> at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:235)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:524)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:546)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:612)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:521)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:510)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestMemoryPostingsFormat -Dtests.method=testDocsAndFreqsAndPositions -Dtests.seed=F21D8556605F9927 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-MA -Dtests.timezone=BST -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.41s J2 | TestMemoryPostingsFormat.testDocsAndFreqsAndPositions <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=616, name=Thread-595, state=RUNNABLE, group=TGRP-TestMemoryPostingsFormat]
[junit4] > Caused by: java.lang.RuntimeException: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] > at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] > Caused by: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] > at org.apache.lucene.util.automaton.Automaton.addTransition(Automaton.java:167)
[junit4] > at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:245)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:537)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:614)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:521)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)Throwable #2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=618, name=Thread-597, state=RUNNABLE, group=TGRP-TestMemoryPostingsFormat]
[junit4] > Caused by: java.lang.RuntimeException: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] > at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] > Caused by: java.lang.IllegalArgumentException: source=3 is out of bounds (maxState is 2)
[junit4] > at org.apache.lucene.util.automaton.Automaton.addTransition(Automaton.java:167)
[junit4] > at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:245)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:537)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:546)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:546)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:519)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)Throwable #3: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=617, name=Thread-596, state=RUNNABLE, group=TGRP-TestMemoryPostingsFormat]
[junit4] > Caused by: java.lang.RuntimeException: java.lang.ArrayIndexOutOfBoundsException: 5
[junit4] > at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] > Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
[junit4] > at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:235)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:524)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:546)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:612)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:521)
[junit4] > at org.apache.lucene.util.automaton.RegExp.findLeaves(RegExp.java:617)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:510)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=crazy): {f_DOCS_AND_FREQS=DFR I(ne)BZ(0.3), field=DFI(Standardized), f_DOCS_AND_FREQS_AND_POSITIONS_AND_OFFSETS=DFI(Standardized), titleTokenized=DFR I(n)L2, f_DOCS_AND_FREQS_AND_POSITIONS=IB LL-D2, body=IB LL-L3(800.0), f_DOCS=DFR I(F)1}, locale=ar-MA, timezone=BST
[junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=302531376,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [TestSimpleTextNormsFormat, TestDirectDocValuesFormat, TestDirectPostingsFormat, TestMemoryPostingsFormat]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestMemoryPostingsFormat -Dtests.seed=F21D8556605F9927 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-MA -Dtests.timezone=BST -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J2 | TestMemoryPostingsFormat (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: The test or suite printed 11784 bytes to stdout and stderr, even though the limit was set to 8192 bytes. Increase the limit with @Limit, ignore it completely with @SuppressSysoutChecks or run with -Dtests.verbose=true
[junit4] > at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] Completed [10/21 (1!)] on J2 in 8.41s, 30 tests, 1 failure, 1 error <<< FAILURES!
[...truncated 64 lines...]
[junit4] Suite: org.apache.lucene.codecs.bloom.TestBloomPostingsFormat
[junit4] 2> Hwe 22, 2016 11:50:24 P.M. com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[Thread-527,5,TGRP-TestBloomPostingsFormat]
[junit4] 2> java.lang.RuntimeException: java.lang.ArrayIndexOutOfBoundsException: 9
[junit4] 2> at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] 2> Caused by: java.lang.ArrayIndexOutOfBoundsException: 9
[junit4] 2> at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:235)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:524)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] 2> at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] 2> at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] 2> at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBloomPostingsFormat -Dtests.method=testDocsAndFreqsAndPositions -Dtests.seed=F21D8556605F9927 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=kw-GB -Dtests.timezone=Africa/Blantyre -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.52s J1 | TestBloomPostingsFormat.testDocsAndFreqsAndPositions <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=548, name=Thread-527, state=RUNNABLE, group=TGRP-TestBloomPostingsFormat]
[junit4] > at __randomizedtesting.SeedInfo.seed([F21D8556605F9927:E83E31C798BC9923]:0)
[junit4] > Caused by: java.lang.RuntimeException: java.lang.ArrayIndexOutOfBoundsException: 9
[junit4] > at __randomizedtesting.SeedInfo.seed([F21D8556605F9927]:0)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1005)
[junit4] > Caused by: java.lang.ArrayIndexOutOfBoundsException: 9
[junit4] > at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:235)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:524)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomSingleAutomaton(AutomatonTestUtil.java:262)
[junit4] > at org.apache.lucene.util.automaton.AutomatonTestUtil.randomAutomaton(AutomatonTestUtil.java:276)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.testTermsOneThread(RandomPostingsTester.java:1166)
[junit4] > at org.apache.lucene.index.RandomPostingsTester.access$000(RandomPostingsTester.java:62)
[junit4] > at org.apache.lucene.index.RandomPostingsTester$TestThread.run(RandomPostingsTester.java:1003)
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=1, maxDocsPerChunk=6, blockSize=2), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=1, blockSize=2)), sim=RandomSimilarity(queryNorm=false,coord=no): {f_DOCS_AND_FREQS=DFR I(F)L1, field=DFR I(F)1, f_DOCS_AND_FREQS_AND_POSITIONS_AND_OFFSETS=IB LL-D2, titleTokenized=DFR I(ne)LZ(0.3), f_DOCS_AND_FREQS_AND_POSITIONS=IB LL-D1, body=DFR I(ne)1, f_DOCS=DFI(Saturated)}, locale=kw-GB, timezone=Africa/Blantyre
[junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=287565024,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [TestAutoPrefixTerms, TestSimpleTextDocValuesFormat, TestFSTPostingsFormat, TestBloomPostingsFormat]
[junit4] Completed [12/21 (2!)] on J1 in 7.56s, 30 tests, 1 error <<< FAILURES!
[...truncated 43 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/build.xml:475: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:2273: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/module-build.xml:58: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: There were test failures: 21 suites, 712 tests, 1 suite-level error, 2 errors, 33 ignored (33 assumptions) [seed: F21D8556605F9927]
Total time: 18 minutes 17 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-5.5-Linux (64bit/jdk-9-ea+106) - Build #
83 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/83/
Java: 64bit/jdk-9-ea+106 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.lucene.index.TestDuelingCodecs.testCrazyReaderEquals
Error Message:
9
Stack Trace:
java.lang.ArrayIndexOutOfBoundsException: 9
at __randomizedtesting.SeedInfo.seed([BF2627FF614F2E18:B1CFDC36D06F31B]:0)
at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:235)
at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:524)
at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
at org.apache.lucene.util.LuceneTestCase.assertTermsEquals(LuceneTestCase.java:1964)
at org.apache.lucene.util.LuceneTestCase.assertFieldsEquals(LuceneTestCase.java:1926)
at org.apache.lucene.util.LuceneTestCase.assertTermVectorsEquals(LuceneTestCase.java:2349)
at org.apache.lucene.util.LuceneTestCase.assertReaderEquals(LuceneTestCase.java:1890)
at org.apache.lucene.index.TestDuelingCodecs.testCrazyReaderEquals(TestDuelingCodecs.java:172)
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:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java: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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:804)
Build Log:
[...truncated 981 lines...]
[junit4] Suite: org.apache.lucene.index.TestDuelingCodecs
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDuelingCodecs -Dtests.method=testCrazyReaderEquals -Dtests.seed=BF2627FF614F2E18 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=gu -Dtests.timezone=HST -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 6.92s J0 | TestDuelingCodecs.testCrazyReaderEquals <<<
[junit4] > Throwable #1: java.lang.ArrayIndexOutOfBoundsException: 9
[junit4] > at __randomizedtesting.SeedInfo.seed([BF2627FF614F2E18:B1CFDC36D06F31B]:0)
[junit4] > at org.apache.lucene.util.automaton.MinimizationOperations.minimize(MinimizationOperations.java:235)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomatonInternal(RegExp.java:524)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:495)
[junit4] > at org.apache.lucene.util.automaton.RegExp.toAutomaton(RegExp.java:426)
[junit4] > at org.apache.lucene.util.LuceneTestCase.assertTermsEquals(LuceneTestCase.java:1964)
[junit4] > at org.apache.lucene.util.LuceneTestCase.assertFieldsEquals(LuceneTestCase.java:1926)
[junit4] > at org.apache.lucene.util.LuceneTestCase.assertTermVectorsEquals(LuceneTestCase.java:2349)
[junit4] > at org.apache.lucene.util.LuceneTestCase.assertReaderEquals(LuceneTestCase.java:1890)
[junit4] > at org.apache.lucene.index.TestDuelingCodecs.testCrazyReaderEquals(TestDuelingCodecs.java:172)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/build/core/test/J0/temp/lucene.index.TestDuelingCodecs_BF2627FF614F2E18-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=DefaultSimilarity, locale=gu, timezone=HST
[junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=357746856,total=527433728
[junit4] 2> NOTE: All tests run in this JVM: [Test2BPostings, TestRoaringDocIdSet, TestDemo, TestIOUtils, TestPersistentSnapshotDeletionPolicy, TestBooleanQuery, TestSparseFixedBitDocIdSet, TestBytesRefHash, TestLRUFilterCache, TestElevationComparator, TestDoc, TestIndexReaderClose, TestFilterLeafReader, TestSortRandom, TestEarlyTermination, Test2BSortedDocValuesFixedSorted, TestNIOFSDirectory, TestSearcherManager, TestSort, TestArrayUtil, Test2BNumericDocValues, TestDocValues, TestStressNRT, TestSpansAdvanced, TestSimilarityProvider, TestIntArrayDocIdSet, TestBagOfPositions, TestTimSorter, TestNeverDelete, TestBooleanScorer, Test2BBinaryDocValues, TestApproximationSearchEquivalence, TestSimpleFSDirectory, TestFilterSpans, TestIndexWriterWithThreads, TestSentinelIntSet, TestMinShouldMatch2, TestMultiset, TestNearSpansOrdered, TestRegexpQuery, TestMultiFields, TestConstantScoreQuery, TestNotDocIdSet, LimitedFiniteStringsIteratorTest, TestIsCurrent, TestLogMergePolicy, TestNot, TestTermVectors, TestDirectPacked, TestSloppyPhraseQuery2, TestSpanContainQuery, TestIndexWriterFromReader, TestPrefixInBooleanQuery, TestUTF32ToUTF8, TestVirtualMethod, TestMultiThreadTermVectors, TestHugeRamFile, TestSegmentReader, TestBooleanRewrites, TestCharTermAttributeImpl, TestDocumentsWriterDeleteQueue, TestIndexCommit, TestUsageTrackingFilterCachingPolicy, TestHighCompressionMode, TestIndexWriterDelete, TestMinimize, TestCodecUtil, Test2BPositions, TestMergePolicyWrapper, TestVersion, Test2BPagedBytes, TestIndexWriterExceptions, TestIndexWriterMergePolicy, TestDuelingCodecs]
[junit4] Completed [212/421 (1!)] on J0 in 13.13s, 2 tests, 1 error <<< FAILURES!
[...truncated 774 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/build.xml:50: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: There were test failures: 421 suites (1 ignored), 3386 tests, 1 error, 146 ignored (142 assumptions) [seed: BF2627FF614F2E18]
Total time: 6 minutes 36 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-5.5-Linux (32bit/jdk-9-ea+106) - Build #
82 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/82/
Java: 32bit/jdk-9-ea+106 -client -XX:+UseG1GC
4 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.SaslZkACLProviderTest
Error Message:
5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest: 1) Thread[id=13109, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 2) Thread[id=13113, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804) 3) Thread[id=13110, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804) 4) Thread[id=13111, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804) 5) Thread[id=13112, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest:
1) Thread[id=13109, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:516)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
2) Thread[id=13113, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
3) Thread[id=13110, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
4) Thread[id=13111, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
5) Thread[id=13112, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
at __randomizedtesting.SeedInfo.seed([13FE044F1F4F941E]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.SaslZkACLProviderTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=13109, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 2) Thread[id=13113, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804) 3) Thread[id=13110, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804) 4) Thread[id=13111, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804) 5) Thread[id=13112, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] at jdk.internal.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632) at java.lang.Thread.run(Thread.java:804)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=13109, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:516)
at java.util.TimerThread.mainLoop(Timer.java:526)
at java.util.TimerThread.run(Timer.java:505)
2) Thread[id=13113, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
3) Thread[id=13110, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
4) Thread[id=13111, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
5) Thread[id=13112, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
at jdk.internal.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
at java.lang.Thread.run(Thread.java:804)
at __randomizedtesting.SeedInfo.seed([13FE044F1F4F941E]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
at __randomizedtesting.SeedInfo.seed([13FE044F1F4F941E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
at sun.reflect.GeneratedMethodAccessor15.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:804)
FAILED: org.apache.solr.cloud.SaslZkACLProviderTest.testSaslZkACLProvider
Error Message:
org.apache.directory.api.ldap.model.exception.LdapOtherException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) '
Stack Trace:
java.lang.RuntimeException: org.apache.directory.api.ldap.model.exception.LdapOtherException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35
NAME 'schemaModifyTimestamp'
DESC time which schema was modified
SUP modifyTimestamp
EQUALITY generalizedTimeMatch
ORDERING generalizedTimeOrderingMatch
SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
USAGE directoryOperation
)
'
at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:210)
at org.apache.solr.cloud.SaslZkACLProviderTest.setUp(SaslZkACLProviderTest.java:80)
at sun.reflect.GeneratedMethodAccessor8.invoke(Unknown Source)
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$9.evaluate(RandomizedRunner.java:905)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java: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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:804)
Caused by: org.apache.directory.api.ldap.model.exception.LdapOtherException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35
NAME 'schemaModifyTimestamp'
DESC time which schema was modified
SUP modifyTimestamp
EQUALITY generalizedTimeMatch
ORDERING generalizedTimeOrderingMatch
SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
USAGE directoryOperation
)
'
at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
at org.apache.directory.server.core.DefaultDirectoryService.initialize(DefaultDirectoryService.java:1808)
at org.apache.directory.server.core.DefaultDirectoryService.startup(DefaultDirectoryService.java:1248)
at org.apache.hadoop.minikdc.MiniKdc.initDirectoryService(MiniKdc.java:383)
at org.apache.hadoop.minikdc.MiniKdc.start(MiniKdc.java:319)
at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:203)
... 38 more
Caused by: java.lang.RuntimeException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35
NAME 'schemaModifyTimestamp'
DESC time which schema was modified
SUP modifyTimestamp
EQUALITY generalizedTimeMatch
ORDERING generalizedTimeOrderingMatch
SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
USAGE directoryOperation
)
'
at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:226)
at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
... 43 more
Caused by: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35
NAME 'schemaModifyTimestamp'
DESC time which schema was modified
SUP modifyTimestamp
EQUALITY generalizedTimeMatch
ORDERING generalizedTimeOrderingMatch
SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
USAGE directoryOperation
)
'
at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:219)
... 44 more
Caused by: org.apache.directory.api.ldap.model.exception.LdapInvalidAttributeValueException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35
NAME 'schemaModifyTimestamp'
DESC time which schema was modified
SUP modifyTimestamp
EQUALITY generalizedTimeMatch
ORDERING generalizedTimeOrderingMatch
SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
USAGE directoryOperation
)
'
at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:211)
at org.apache.directory.api.ldap.model.entry.StringValue.<init>(StringValue.java:107)
at org.apache.directory.api.ldap.model.entry.DefaultAttribute.<init>(DefaultAttribute.java:468)
at org.apache.directory.api.ldap.model.entry.DefaultEntry.<init>(DefaultEntry.java:315)
at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:517)
at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:549)
at org.apache.directory.server.core.partition.ldif.LdifPartition.doInit(LdifPartition.java:164)
at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
... 45 more
Caused by: org.apache.directory.api.ldap.model.exception.LdapInvalidAttributeValueException: ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35
NAME 'schemaModifyTimestamp'
DESC time which schema was modified
SUP modifyTimestamp
EQUALITY generalizedTimeMatch
ORDERING generalizedTimeOrderingMatch
SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
USAGE directoryOperation
)
'
at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:204)
... 52 more
Build Log:
[...truncated 11429 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/init-core-data-001
[junit4] 2> 690159 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 690160 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1
[junit4] 2> 690163 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 690164 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2b0f88{/solr,null,AVAILABLE}
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@58dea3{HTTP/1.1}{127.0.0.1:41670}
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.Server Started @691746ms
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=41670}
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@163006a
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001'
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 690165 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/solr.xml
[junit4] 2> 690169 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/.
[junit4] 2> 690169 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer New CoreContainer 20319909
[junit4] 2> 690169 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001]
[junit4] 2> 690169 WARN (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/lib
[junit4] 2> 690170 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] 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> 690171 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 690171 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 690172 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 690172 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 690172 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 690172 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/.
[junit4] 2> 690181 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 690181 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1
[junit4] 2> 690181 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 690182 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1'
[junit4] 2> 690182 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 690182 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 690182 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 690182 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 690188 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 690191 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 690196 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 690199 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 690201 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 690202 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1'
[junit4] 2> 690202 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1
[junit4] 2> 690203 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 690203 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/]
[junit4] 2> 690203 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 690203 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data
[junit4] 2> 690203 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 690203 WARN (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 690203 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index
[junit4] 2> 690204 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.212890625, noCFSRatio=0.8858414711317493]
[junit4] 2> 690204 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@158dd7e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3cd2a6),segFN=segments_1,generation=1}
[junit4] 2> 690204 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 690204 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/
[junit4] 2> 690205 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 690207 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690207 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690208 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690208 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690212 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 690212 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 690212 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 690212 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 690212 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 690212 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=8, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3932500490546761]
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@158dd7e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3cd2a6),segFN=segments_1,generation=1}
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@1d27491[collection1] main
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/conf
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/conf}
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/conf
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 690213 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 690214 INFO (searcherExecutor-1950-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1d27491[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 690214 INFO (coreLoadExecutor-1949-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 690684 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1
[junit4] 2> 690686 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 690687 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@dd6ca4{/solr,null,AVAILABLE}
[junit4] 2> 690687 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@173f913{HTTP/1.1}{127.0.0.1:42670}
[junit4] 2> 690687 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.Server Started @692268ms
[junit4] 2> 690687 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=42670}
[junit4] 2> 690687 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@163006a
[junit4] 2> 690687 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002'
[junit4] 2> 690688 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 690688 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 690688 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/solr.xml
[junit4] 2> 690692 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/.
[junit4] 2> 690692 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer New CoreContainer 11929018
[junit4] 2> 690692 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002]
[junit4] 2> 690692 WARN (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/lib
[junit4] 2> 690692 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] 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> 690693 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 690694 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 690694 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 690694 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 690694 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 690694 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/.
[junit4] 2> 690695 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 690695 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1
[junit4] 2> 690695 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 690695 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1'
[junit4] 2> 690696 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 690696 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 690696 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 690696 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 690702 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 690705 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 690710 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 690713 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 690715 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 690716 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1'
[junit4] 2> 690716 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1
[junit4] 2> 690716 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 690716 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/]
[junit4] 2> 690716 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 690717 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data
[junit4] 2> 690717 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 690717 WARN (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 690717 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/index
[junit4] 2> 690717 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.212890625, noCFSRatio=0.8858414711317493]
[junit4] 2> 690718 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1a696f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@22ec91),segFN=segments_1,generation=1}
[junit4] 2> 690718 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 690718 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/
[junit4] 2> 690719 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 690720 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690721 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690722 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690723 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 690723 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 690724 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 690724 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 690724 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 690724 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 690724 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=8, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3932500490546761]
[junit4] 2> 690724 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1a696f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@22ec91),segFN=segments_1,generation=1}
[junit4] 2> 690724 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 690725 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@8ba7af[collection1] main
[junit4] 2> 690725 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1/conf
[junit4] 2> 690725 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1/conf}
[junit4] 2> 690725 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/collection1/conf
[junit4] 2> 690725 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 690727 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 690727 INFO (searcherExecutor-1960-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8ba7af[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 690727 INFO (coreLoadExecutor-1959-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 690837 INFO (qtp14953660-3827) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 691197 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@173f913{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 691197 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=11929018
[junit4] 2> 691197 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12f924b
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data/index
[junit4] 2> 691198 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 691199 INFO (coreCloseExecutor-1965-thread-1) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-002/./collection1/data
[junit4] 2> 691199 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@dd6ca4{/solr,null,UNAVAILABLE}
[junit4] 2> 691201 INFO (qtp14953660-3829) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 691201 INFO (qtp14953660-3829) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 691203 INFO (qtp14953660-3827) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 691203 INFO (qtp14953660-3827) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@158dd7e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3cd2a6),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@158dd7e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3cd2a6),segFN=segments_2,generation=2}
[junit4] 2> 691204 INFO (qtp14953660-3827) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 691204 INFO (qtp14953660-3827) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@798462[collection1] main
[junit4] 2> 691204 INFO (qtp14953660-3827) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 691204 INFO (searcherExecutor-1950-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@798462[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 691204 INFO (qtp14953660-3827) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 691206 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@58dea3{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 691206 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=20319909
[junit4] 2> 691206 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@d089be
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data;done=false>>]
[junit4] 2> 691207 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data
[junit4] 2> 691208 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index;done=false>>]
[junit4] 2> 691208 INFO (coreCloseExecutor-1966-thread-1) [ ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index
[junit4] 2> 691208 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2b0f88{/solr,null,UNAVAILABLE}
[junit4] 2> 691209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 691210 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@afe061{/solr,null,AVAILABLE}
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@176276d{HTTP/1.1}{127.0.0.1:51063}
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.e.j.s.Server Started @692792ms
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=51063}
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@163006a
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001'
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 691211 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/solr.xml
[junit4] 2> 691219 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/.
[junit4] 2> 691219 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer New CoreContainer 25227711
[junit4] 2> 691219 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001]
[junit4] 2> 691219 WARN (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/lib
[junit4] 2> 691219 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] 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> 691220 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 691221 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 691221 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 691221 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 691221 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 691221 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/.
[junit4] 2> 691223 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 691223 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1
[junit4] 2> 691223 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 691223 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1'
[junit4] 2> 691223 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 691224 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[13FE044F1F4F941E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 691223 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 691224 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 691231 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 691235 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 691242 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 691245 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 691246 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 691248 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1'
[junit4] 2> 691248 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1
[junit4] 2> 691248 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrCore solr.NRTCachingDirectoryFactory
[junit4] 2> 691253 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/]
[junit4] 2> 691253 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 691253 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/data
[junit4] 2> 691253 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 691253 WARN (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 691254 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/data/index
[junit4] 2> 691254 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1737189712, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 691300 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11f4228; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 691300 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 691300 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/./collection1/data/
[junit4] 2> 691301 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 691302 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 691303 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 691304 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 691304 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 691305 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 691305 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 691305 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 691306 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 691306 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 691306 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=57.3759765625, floorSegmentMB=1.806640625, forceMergeDeletesPctAllowed=15.590963504747378, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 691306 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11f4228; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 691306 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 691306 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@477d5a[collection1] main
[junit4] 2> 691307 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/conf
[junit4] 2> 691307 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/conf}
[junit4] 2> 691307 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_13FE044F1F4F941E-001/solr-instance-001/collection1/conf
[junit4] 2> 691307 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 691307 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 691307 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 691307 INFO (searcherExecutor-1973-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@477d5a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 691307 INFO (coreLoadExecutor-1972-thread-1) [ ] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 691727 INFO (qtp29693723-3869) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 691730 INFO (qtp29693723-3870) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 691732 INFO (qtp29693723-3871) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 691733 INFO (qtp29693723-3869) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&versio
[...truncated too long message...]
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 3) Thread[id=13110, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 4) Thread[id=13111, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 5) Thread[id=13112, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > at __randomizedtesting.SeedInfo.seed([13FE044F1F4F941E]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=13109, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Object.wait(Object.java:516)
[junit4] > at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] > at java.util.TimerThread.run(Timer.java:505)
[junit4] > 2) Thread[id=13113, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 3) Thread[id=13110, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 4) Thread[id=13111, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 5) Thread[id=13112, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] > at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > at __randomizedtesting.SeedInfo.seed([13FE044F1F4F941E]:0)
[junit4] Completed [436/566 (2!)] on J2 in 25.71s, 1 test, 3 errors <<< FAILURES!
[...truncated 413 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/common-build.xml:534: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: There were test failures: 566 suites (7 ignored), 2275 tests, 3 suite-level errors, 1 error, 484 ignored (35 assumptions) [seed: 13FE044F1F4F941E]
Total time: 60 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