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/14 23:51:25 UTC
[JENKINS-EA] Lucene-Solr-5.5-Linux (64bit/jdk-9-ea+104) - Build # 8
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/8/
Java: 64bit/jdk-9-ea+104 -XX:+UseCompressedOops -XX:+UseG1GC -XX:-CompactStrings -XX:-UseSuperWord
3 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=5130, 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) 2) Thread[id=5129, 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) 3) Thread[id=5133, 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=5131, 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) 5) Thread[id=5132, 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=5130, 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)
2) Thread[id=5129, 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)
3) Thread[id=5133, 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=5131, 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)
5) Thread[id=5132, 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([D714505623ECAB10]: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=5130, 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) 2) Thread[id=5129, 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) 3) Thread[id=5133, 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=5131, 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) 5) Thread[id=5132, 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=5130, 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)
2) Thread[id=5129, 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)
3) Thread[id=5133, 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=5131, 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)
5) Thread[id=5132, 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([D714505623ECAB10]:0)
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 11364 lines...]
[junit4] Suite: org.apache.solr.cloud.SaslZkACLProviderTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SaslZkACLProviderTest_D714505623ECAB10-001/init-core-data-001
[junit4] 2> 666847 INFO (SUITE-SaslZkACLProviderTest-seed#[D714505623ECAB10]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 666852 INFO (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[D714505623ECAB10]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSaslZkACLProvider
[junit4] 2> 666853 INFO (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[D714505623ECAB10]) [ ] o.a.s.c.SaslZkACLProviderTest ####SETUP_START testSaslZkACLProvider
[junit4] 2> 666853 INFO (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[D714505623ECAB10]) [ ] o.a.s.c.SaslZkACLProviderTest ZooKeeper dataDir:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SaslZkACLProviderTest_D714505623ECAB10-001/tempDir-002/zookeeper/server1/data
[junit4] 2> 668928 WARN (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[D714505623ECAB10]) [ ] o.a.d.a.l.m.e.DefaultEntry The attribute 'schemamodifytimestamp' cannot be stored
[junit4] 2> 668946 ERROR (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[D714505623ECAB10]) [ ] o.a.d.s.c.a.s.SchemaPartition ERR_90 Failed to initialize wrapped partition.
[junit4] 2> 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
[junit4] 2> NAME 'schemaModifyTimestamp'
[junit4] 2> DESC time which schema was modified
[junit4] 2> SUP modifyTimestamp
[junit4] 2> EQUALITY generalizedTimeMatch
[junit4] 2> ORDERING generalizedTimeOrderingMatch
[junit4] 2> SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] 2> USAGE directoryOperation
[junit4] 2> )
[junit4] 2> '
[junit4] 2> at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
[junit4] 2> at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:219)
[junit4] 2> at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] 2> at org.apache.directory.server.core.DefaultDirectoryService.initialize(DefaultDirectoryService.java:1808)
[junit4] 2> at org.apache.directory.server.core.DefaultDirectoryService.startup(DefaultDirectoryService.java:1248)
[junit4] 2> at org.apache.hadoop.minikdc.MiniKdc.initDirectoryService(MiniKdc.java:383)
[junit4] 2> at org.apache.hadoop.minikdc.MiniKdc.start(MiniKdc.java:319)
[junit4] 2> at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:203)
[junit4] 2> at org.apache.solr.cloud.SaslZkACLProviderTest.setUp(SaslZkACLProviderTest.java:80)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor8.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:520)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:905)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 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
[junit4] 2> NAME 'schemaModifyTimestamp'
[junit4] 2> DESC time which schema was modified
[junit4] 2> SUP modifyTimestamp
[junit4] 2> EQUALITY generalizedTimeMatch
[junit4] 2> ORDERING generalizedTimeOrderingMatch
[junit4] 2> SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] 2> USAGE directoryOperation
[junit4] 2> )
[junit4] 2> '
[junit4] 2> at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:211)
[junit4] 2> at org.apache.directory.api.ldap.model.entry.StringValue.<init>(StringValue.java:107)
[junit4] 2> at org.apache.directory.api.ldap.model.entry.DefaultAttribute.<init>(DefaultAttribute.java:468)
[junit4] 2> at org.apache.directory.api.ldap.model.entry.DefaultEntry.<init>(DefaultEntry.java:315)
[junit4] 2> at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:517)
[junit4] 2> at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:549)
[junit4] 2> at org.apache.directory.server.core.partition.ldif.LdifPartition.doInit(LdifPartition.java:164)
[junit4] 2> at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] 2> ... 45 more
[junit4] 2> 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
[junit4] 2> NAME 'schemaModifyTimestamp'
[junit4] 2> DESC time which schema was modified
[junit4] 2> SUP modifyTimestamp
[junit4] 2> EQUALITY generalizedTimeMatch
[junit4] 2> ORDERING generalizedTimeOrderingMatch
[junit4] 2> SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] 2> USAGE directoryOperation
[junit4] 2> )
[junit4] 2> '
[junit4] 2> at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:204)
[junit4] 2> ... 52 more
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SaslZkACLProviderTest -Dtests.method=testSaslZkACLProvider -Dtests.seed=D714505623ECAB10 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-001 -Dtests.timezone=Europe/Simferopol -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 2.10s J0 | SaslZkACLProviderTest.testSaslZkACLProvider <<<
[junit4] > Throwable #1: 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:210)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest.setUp(SaslZkACLProviderTest.java:80)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
[junit4] > at org.apache.directory.server.core.DefaultDirectoryService.initialize(DefaultDirectoryService.java:1808)
[junit4] > at org.apache.directory.server.core.DefaultDirectoryService.startup(DefaultDirectoryService.java:1248)
[junit4] > at org.apache.hadoop.minikdc.MiniKdc.initDirectoryService(MiniKdc.java:383)
[junit4] > at org.apache.hadoop.minikdc.MiniKdc.start(MiniKdc.java:319)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:203)
[junit4] > ... 38 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:226)
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] > ... 43 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
[junit4] > at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:219)
[junit4] > ... 44 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:211)
[junit4] > at org.apache.directory.api.ldap.model.entry.StringValue.<init>(StringValue.java:107)
[junit4] > at org.apache.directory.api.ldap.model.entry.DefaultAttribute.<init>(DefaultAttribute.java:468)
[junit4] > at org.apache.directory.api.ldap.model.entry.DefaultEntry.<init>(DefaultEntry.java:315)
[junit4] > at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:517)
[junit4] > at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:549)
[junit4] > at org.apache.directory.server.core.partition.ldif.LdifPartition.doInit(LdifPartition.java:164)
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] > ... 45 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:204)
[junit4] > ... 52 moreThrowable #2: java.lang.NullPointerException
[junit4] > at org.apache.solr.cloud.ZkTestServer$ZKServerMain.shutdown(ZkTestServer.java:334)
[junit4] > at org.apache.solr.cloud.ZkTestServer.shutdown(ZkTestServer.java:526)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.shutdown(SaslZkACLProviderTest.java:217)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest.tearDown(SaslZkACLProviderTest.java:115)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 668950 INFO (SUITE-SaslZkACLProviderTest-seed#[D714505623ECAB10]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SaslZkACLProviderTest_D714505623ECAB10-001
[junit4] 2> Feb 14, 2016 10:47:07 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 5 leaked thread(s).
[junit4] 2> Feb 14, 2016 10:47:27 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest:
[junit4] 2> 1) Thread[id=5130, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 2) Thread[id=5129, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:516)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> 3) Thread[id=5133, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 4) Thread[id=5131, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 5) Thread[id=5132, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> Feb 14, 2016 10:47:27 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=5130, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 2) Thread[id=5129, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 3) Thread[id=5133, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 4) Thread[id=5131, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 5) Thread[id=5132, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> Feb 14, 2016 10:47:30 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=5130, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 2) Thread[id=5129, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:516)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> 3) Thread[id=5133, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 4) Thread[id=5131, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 5) Thread[id=5132, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=ar-001, timezone=Europe/Simferopol
[junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=6,free=176188640,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DistributedFacetPivotLargeTest, BlockJoinFacetDistribTest, IndexSchemaRuntimeFieldTest, TestBadConfig, TestComplexPhraseQParserPlugin, SOLR749Test, BasicZkTest, PolyFieldTest, TestDistributedStatsComponentCardinality, TestInfoStreamLogging, ResponseLogComponentTest, TestDistributedSearch, BadCopyFieldTest, CloudMLTQParserTest, OverseerRolesTest, ResponseHeaderTest, RAMDirectoryFactoryTest, TestConfigOverlay, ExplicitHLLTest, TestStandardQParsers, TestSchemaManager, TestScoreJoinQPNoScore, TestPseudoReturnFields, SpatialHeatmapFacetsTest, TestBulkSchemaConcurrent, CacheHeaderTest, TestSolr4Spatial, DocumentAnalysisRequestHandlerTest, RestartWhileUpdatingTest, CustomCollectionTest, TestConfig, TestLeaderElectionZkExpiry, AnalysisAfterCoreReloadTest, TestCloudSchemaless, TestFieldTypeResource, EchoParamsTest, RulesTest, LeaderElectionIntegrationTest, RequiredFieldsTest, TestPhraseSuggestions, TestBinaryField, TestRandomDVFaceting, DateFieldTest, TestConfigSets, LeaderInitiatedRecoveryOnShardRestartTest, TestBulkSchemaAPI, CloneFieldUpdateProcessorFactoryTest, DistributedQueryElevationComponentTest, SaslZkACLProviderTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SaslZkACLProviderTest -Dtests.seed=D714505623ECAB10 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-001 -Dtests.timezone=Europe/Simferopol -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | SaslZkACLProviderTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest:
[junit4] > 1) Thread[id=5130, 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] > 2) Thread[id=5129, 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] > 3) Thread[id=5133, 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=5131, 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] > 5) Thread[id=5132, 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([D714505623ECAB10]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=5130, 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] > 2) Thread[id=5129, 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] > 3) Thread[id=5133, 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=5131, 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] > 5) Thread[id=5132, 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([D714505623ECAB10]:0)
[junit4] Completed [237/566 (1!)] on J0 in 25.33s, 1 test, 3 errors <<< FAILURES!
[...truncated 1020 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, 2 suite-level errors, 1 error, 1303 ignored (32 assumptions) [seed: D714505623ECAB10]
Total time: 35 minutes 14 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+104) - Build #
11 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/11/
Java: 32bit/jdk-9-ea+104 -client -XX:+UseConcMarkSweepGC -XX:-CompactStrings
3 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=12415, 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) 2) Thread[id=12417, 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) 3) Thread[id=12414, 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) 4) Thread[id=12413, 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) 5) Thread[id=12416, 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)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest:
1) Thread[id=12415, 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)
2) Thread[id=12417, 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)
3) Thread[id=12414, 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)
4) Thread[id=12413, 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)
5) Thread[id=12416, 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)
at __randomizedtesting.SeedInfo.seed([6A038B2321BB472]: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=12415, 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) 2) Thread[id=12417, 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) 3) Thread[id=12414, 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) 4) Thread[id=12413, 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) 5) Thread[id=12416, 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)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=12415, 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)
2) Thread[id=12417, 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)
3) Thread[id=12414, 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)
4) Thread[id=12413, 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)
5) Thread[id=12416, 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)
at __randomizedtesting.SeedInfo.seed([6A038B2321BB472]:0)
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.GeneratedMethodAccessor13.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 12012 lines...]
[junit4] Suite: org.apache.solr.cloud.SaslZkACLProviderTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SaslZkACLProviderTest_6A038B2321BB472-001/init-core-data-001
[junit4] 2> 1466092 INFO (SUITE-SaslZkACLProviderTest-seed#[6A038B2321BB472]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1466095 INFO (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[6A038B2321BB472]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSaslZkACLProvider
[junit4] 2> 1466095 INFO (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[6A038B2321BB472]) [ ] o.a.s.c.SaslZkACLProviderTest ####SETUP_START testSaslZkACLProvider
[junit4] 2> 1466096 INFO (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[6A038B2321BB472]) [ ] o.a.s.c.SaslZkACLProviderTest ZooKeeper dataDir:/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SaslZkACLProviderTest_6A038B2321BB472-001/tempDir-002/zookeeper/server1/data
[junit4] 2> 1468274 WARN (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[6A038B2321BB472]) [ ] o.a.d.a.l.m.e.DefaultEntry The attribute 'schemamodifytimestamp' cannot be stored
[junit4] 2> 1468292 ERROR (TEST-SaslZkACLProviderTest.testSaslZkACLProvider-seed#[6A038B2321BB472]) [ ] o.a.d.s.c.a.s.SchemaPartition ERR_90 Failed to initialize wrapped partition.
[junit4] 2> 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
[junit4] 2> NAME 'schemaModifyTimestamp'
[junit4] 2> DESC time which schema was modified
[junit4] 2> SUP modifyTimestamp
[junit4] 2> EQUALITY generalizedTimeMatch
[junit4] 2> ORDERING generalizedTimeOrderingMatch
[junit4] 2> SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] 2> USAGE directoryOperation
[junit4] 2> )
[junit4] 2> '
[junit4] 2> at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
[junit4] 2> at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:219)
[junit4] 2> at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] 2> at org.apache.directory.server.core.DefaultDirectoryService.initialize(DefaultDirectoryService.java:1808)
[junit4] 2> at org.apache.directory.server.core.DefaultDirectoryService.startup(DefaultDirectoryService.java:1248)
[junit4] 2> at org.apache.hadoop.minikdc.MiniKdc.initDirectoryService(MiniKdc.java:383)
[junit4] 2> at org.apache.hadoop.minikdc.MiniKdc.start(MiniKdc.java:319)
[junit4] 2> at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:203)
[junit4] 2> at org.apache.solr.cloud.SaslZkACLProviderTest.setUp(SaslZkACLProviderTest.java:80)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor13.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:520)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:905)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 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
[junit4] 2> NAME 'schemaModifyTimestamp'
[junit4] 2> DESC time which schema was modified
[junit4] 2> SUP modifyTimestamp
[junit4] 2> EQUALITY generalizedTimeMatch
[junit4] 2> ORDERING generalizedTimeOrderingMatch
[junit4] 2> SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] 2> USAGE directoryOperation
[junit4] 2> )
[junit4] 2> '
[junit4] 2> at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:211)
[junit4] 2> at org.apache.directory.api.ldap.model.entry.StringValue.<init>(StringValue.java:107)
[junit4] 2> at org.apache.directory.api.ldap.model.entry.DefaultAttribute.<init>(DefaultAttribute.java:468)
[junit4] 2> at org.apache.directory.api.ldap.model.entry.DefaultEntry.<init>(DefaultEntry.java:315)
[junit4] 2> at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:517)
[junit4] 2> at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:549)
[junit4] 2> at org.apache.directory.server.core.partition.ldif.LdifPartition.doInit(LdifPartition.java:164)
[junit4] 2> at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] 2> ... 45 more
[junit4] 2> 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
[junit4] 2> NAME 'schemaModifyTimestamp'
[junit4] 2> DESC time which schema was modified
[junit4] 2> SUP modifyTimestamp
[junit4] 2> EQUALITY generalizedTimeMatch
[junit4] 2> ORDERING generalizedTimeOrderingMatch
[junit4] 2> SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] 2> USAGE directoryOperation
[junit4] 2> )
[junit4] 2> '
[junit4] 2> at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:204)
[junit4] 2> ... 52 more
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SaslZkACLProviderTest -Dtests.method=testSaslZkACLProvider -Dtests.seed=6A038B2321BB472 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-ER -Dtests.timezone=America/Coral_Harbour -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 2.21s J0 | SaslZkACLProviderTest.testSaslZkACLProvider <<<
[junit4] > Throwable #1: 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:210)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest.setUp(SaslZkACLProviderTest.java:80)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
[junit4] > at org.apache.directory.server.core.DefaultDirectoryService.initialize(DefaultDirectoryService.java:1808)
[junit4] > at org.apache.directory.server.core.DefaultDirectoryService.startup(DefaultDirectoryService.java:1248)
[junit4] > at org.apache.hadoop.minikdc.MiniKdc.initDirectoryService(MiniKdc.java:383)
[junit4] > at org.apache.hadoop.minikdc.MiniKdc.start(MiniKdc.java:319)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:203)
[junit4] > ... 38 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:226)
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] > ... 43 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84)
[junit4] > at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:219)
[junit4] > ... 44 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:211)
[junit4] > at org.apache.directory.api.ldap.model.entry.StringValue.<init>(StringValue.java:107)
[junit4] > at org.apache.directory.api.ldap.model.entry.DefaultAttribute.<init>(DefaultAttribute.java:468)
[junit4] > at org.apache.directory.api.ldap.model.entry.DefaultEntry.<init>(DefaultEntry.java:315)
[junit4] > at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:517)
[junit4] > at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:549)
[junit4] > at org.apache.directory.server.core.partition.ldif.LdifPartition.doInit(LdifPartition.java:164)
[junit4] > at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79)
[junit4] > ... 45 more
[junit4] > 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
[junit4] > NAME 'schemaModifyTimestamp'
[junit4] > DESC time which schema was modified
[junit4] > SUP modifyTimestamp
[junit4] > EQUALITY generalizedTimeMatch
[junit4] > ORDERING generalizedTimeOrderingMatch
[junit4] > SYNTAX 1.3.6.1.4.1.1466.115.121.1.24
[junit4] > USAGE directoryOperation
[junit4] > )
[junit4] > '
[junit4] > at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:204)
[junit4] > ... 52 moreThrowable #2: java.lang.NullPointerException
[junit4] > at org.apache.solr.cloud.ZkTestServer$ZKServerMain.shutdown(ZkTestServer.java:334)
[junit4] > at org.apache.solr.cloud.ZkTestServer.shutdown(ZkTestServer.java:526)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.shutdown(SaslZkACLProviderTest.java:217)
[junit4] > at org.apache.solr.cloud.SaslZkACLProviderTest.tearDown(SaslZkACLProviderTest.java:115)
[junit4] > at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 1468297 INFO (SUITE-SaslZkACLProviderTest-seed#[6A038B2321BB472]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SaslZkACLProviderTest_6A038B2321BB472-001
[junit4] 2> Feb 15, 2016 7:32:27 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 5 leaked thread(s).
[junit4] 2> Feb 15, 2016 7:32:47 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest:
[junit4] 2> 1) Thread[id=12415, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 2) Thread[id=12417, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 3) Thread[id=12414, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 4) Thread[id=12413, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:516)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> 5) Thread[id=12416, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> Feb 15, 2016 7:32:47 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=12415, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 2) Thread[id=12417, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 3) Thread[id=12414, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 4) Thread[id=12413, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> 5) Thread[id=12416, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> Feb 15, 2016 7:32:50 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=12415, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 2) Thread[id=12417, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 3) Thread[id=12414, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> 4) Thread[id=12413, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:516)
[junit4] 2> at java.util.TimerThread.mainLoop(Timer.java:526)
[junit4] 2> at java.util.TimerThread.run(Timer.java:505)
[junit4] 2> 5) Thread[id=12416, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest]
[junit4] 2> at jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:230)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2106)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1083)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1143)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:632)
[junit4] 2> at java.lang.Thread.run(Thread.java:804)
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=1, maxDocsPerChunk=8, blockSize=899), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=1, blockSize=899)), sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=ar-ER, timezone=America/Coral_Harbour
[junit4] 2> NOTE: Linux 3.19.0-47-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=6,free=305493576,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestTrackingShardHandlerFactory, DisMaxRequestHandlerTest, UUIDFieldTest, DeleteReplicaTest, PreAnalyzedFieldTest, DocumentAnalysisRequestHandlerTest, TestBlobHandler, SynonymTokenizerTest, CustomCollectionTest, SpatialHeatmapFacetsTest, RulesTest, FileBasedSpellCheckerTest, HLLUtilTest, DistributedSpellCheckComponentTest, DateFieldTest, TestFastOutputStream, TestRequestForwarding, TestCloudSchemaless, IndexSchemaTest, RAMDirectoryFactoryTest, TestSchemaManager, TestConfig, HdfsBasicDistributedZk2Test, TestAnalyzedSuggestions, ResponseHeaderTest, BlockJoinFacetDistribTest, TestStressLucene, SharedFSAutoReplicaFailoverUtilsTest, TestLRUCache, CoreMergeIndexesAdminHandlerTest, JsonLoaderTest, SolrXmlInZkTest, TestDistribDocBasedVersion, IndexSchemaRuntimeFieldTest, TestOrdValues, WrapperMergePolicyFactoryTest, TestSerializedLuceneMatchVersion, AsyncCallRequestStatusResponseTest, TestFoldingMultitermQuery, TestLeaderElectionZkExpiry, ResourceLoaderTest, PrimUtilsTest, QueryEqualityTest, TestFunctionQuery, SOLR749Test, PolyFieldTest, TestDistributedSearch, TestCrossCoreJoin, LeaderInitiatedRecoveryOnShardRestartTest, SmileWriterTest, MergeStrategyTest, ParsingFieldUpdateProcessorsTest, PathHierarchyTokenizerFactoryTest, TestXmlQParser, TestReqParamsAPI, HdfsWriteToMultipleCollectionsTest, TestCloudPivotFacet, RequiredFieldsTest, TestAddFieldRealTimeGet, RecoveryAfterSoftCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestRTGBase, TestSolrCloudWithKerberosAlt, ZkNodePropsTest, TestSolr4Spatial, TestBulkSchemaConcurrent, TestPseudoReturnFields, TestBinaryField, SolrCoreTest, ExitableDirectoryReaderTest, SegmentsInfoRequestHandlerTest, TestHighlightDedupGrouping, BigEndianAscendingWordDeserializerTest, CollectionStateFormat2Test, TestFieldCollectionResource, CursorPagingTest, TestDefaultSearchFieldResource, TestScoreJoinQPNoScore, BufferStoreTest, DistributedQueryElevationComponentTest, ExternalFileFieldSortTest, CloudMLTQParserTest, TestRealTimeGet, TestLuceneMatchVersion, TestAtomicUpdateErrorCases, TestConfigSets, StandardRequestHandlerTest, AddBlockUpdateTest, TestBadConfig, HdfsSyncSliceTest, PeerSyncTest, CopyFieldTest, TestRandomRequestDistribution, SyncSliceTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler, AutoCommitTest, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestJoin, DistributedTermsComponentTest, TestGroupingSearch, QueryElevationComponentTest, BasicFunctionalityTest, TestLazyCores, TestIndexSearcher, ShowFileRequestHandlerTest, SolrIndexSplitterTest, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, CoreAdminHandlerTest, TestTrie, TestCSVLoader, SolrCoreCheckLockOnStartupTest, TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, XsltUpdateRequestHandlerTest, LukeRequestHandlerTest, RequestHandlersTest, TestValueSourceCache, TestSolrQueryParser, LoggingHandlerTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, PingRequestHandlerTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, SampleTest, TestDocSet, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport, EchoParamsTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TimeZoneUtilsTest, URLClassifyProcessorTest, DateMathParserTest, SpellingQueryConverterTest, DOMUtilTest, TestSolrJ, TestUtils, SystemInfoHandlerTest, FileUtilsTest, CircularListTest, SolrTestCaseJ4Test, TestSimpleTrackingShardHandler, TestJettySolrRunner, ActionThrottleTest, AliasIntegrationTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, DistribJoinFromCollectionTest, HttpPartitionTest, SaslZkACLProviderTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SaslZkACLProviderTest -Dtests.seed=6A038B2321BB472 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-ER -Dtests.timezone=America/Coral_Harbour -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | SaslZkACLProviderTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest:
[junit4] > 1) Thread[id=12415, 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] > 2) Thread[id=12417, 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] > 3) Thread[id=12414, 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] > 4) Thread[id=12413, 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] > 5) Thread[id=12416, 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] > at __randomizedtesting.SeedInfo.seed([6A038B2321BB472]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=12415, 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] > 2) Thread[id=12417, 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] > 3) Thread[id=12414, 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] > 4) Thread[id=12413, 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] > 5) Thread[id=12416, 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] > at __randomizedtesting.SeedInfo.seed([6A038B2321BB472]:0)
[junit4] Completed [441/566 (1!)] on J0 in 25.28s, 1 test, 3 errors <<< FAILURES!
[...truncated 403 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, 2 suite-level errors, 1 error, 470 ignored (39 assumptions) [seed: 6A038B2321BB472]
Total time: 55 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_72) - Build # 10 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/10/
Java: 64bit/jdk1.8.0_72 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Mon Feb 15 07:10:27 AST 2016
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Mon Feb 15 07:10:27 AST 2016
at __randomizedtesting.SeedInfo.seed([8A5E7E72F9D7974F:51F57EB4FCFFFEFC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1419)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:771)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at 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:745)
FAILED: org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes
Error Message:
expected:<5> but was:<4>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<4>
at __randomizedtesting.SeedInfo.seed([8A5E7E72F9D7974F:A6273AF78C6E5FEA]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes(DirectUpdateHandlerTest.java:299)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at 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:745)
Build Log:
[...truncated 11248 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_8A5E7E72F9D7974F-001/init-core-data-001
[junit4] 2> 710599 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestStressReplication
[junit4] 2> 710599 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1
[junit4] 2> 710603 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 710604 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@77690437{/solr,null,AVAILABLE}
[junit4] 2> 710608 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@bea6842{HTTP/1.1}{127.0.0.1:41546}
[junit4] 2> 710608 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server Started @712510ms
[junit4] 2> 710608 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=41546}
[junit4] 2> 710608 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 710608 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001'
[junit4] 2> 710608 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 710608 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 710609 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/solr.xml
[junit4] 2> 710612 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/.
[junit4] 2> 710612 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer New CoreContainer 64625306
[junit4] 2> 710613 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001]
[junit4] 2> 710613 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-001/lib
[junit4] 2> 710613 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 710614 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 710614 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 710614 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 710614 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 710614 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 710614 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/.
[junit4] 2> 710615 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 710615 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1
[junit4] 2> 710615 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 710615 INFO (coreLoadExecutor-2003-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_8A5E7E72F9D7974F-001/solr-instance-001/collection1'
[junit4] 2> 710615 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 710615 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 710616 INFO (coreLoadExecutor-2003-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 710616 INFO (coreLoadExecutor-2003-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 710623 INFO (coreLoadExecutor-2003-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 710626 INFO (coreLoadExecutor-2003-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 710656 INFO (coreLoadExecutor-2003-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 710659 INFO (coreLoadExecutor-2003-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 710661 INFO (coreLoadExecutor-2003-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 710663 INFO (coreLoadExecutor-2003-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_8A5E7E72F9D7974F-001/solr-instance-001/collection1'
[junit4] 2> 710663 INFO (coreLoadExecutor-2003-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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1
[junit4] 2> 710663 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 710664 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/]
[junit4] 2> 710664 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 710664 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data
[junit4] 2> 710664 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 710664 WARN (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 710664 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/index
[junit4] 2> 710665 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.32263961256201706]
[junit4] 2> 710665 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@38ce1dc7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4156010),segFN=segments_1,generation=1}
[junit4] 2> 710665 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 710666 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x: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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/
[junit4] 2> 710667 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 710669 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 710669 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 710670 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 710671 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 710674 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 710674 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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> 710675 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 710675 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 710675 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 710675 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=33.3779296875, floorSegmentMB=2.0009765625, forceMergeDeletesPctAllowed=20.52779558983096, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 710675 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@38ce1dc7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4156010),segFN=segments_1,generation=1}
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@72b0b10c[collection1] main
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/conf
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/conf}
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/conf
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 710676 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 710676 INFO (searcherExecutor-2004-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72b0b10c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 710677 INFO (coreLoadExecutor-2003-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 711116 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1
[junit4] 2> 711118 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 711119 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@63580c3{/solr,null,AVAILABLE}
[junit4] 2> 711119 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@98f9518{HTTP/1.1}{127.0.0.1:58169}
[junit4] 2> 711119 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server Started @713022ms
[junit4] 2> 711119 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=58169}
[junit4] 2> 711119 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 711120 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002'
[junit4] 2> 711120 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 711120 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 711120 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/solr.xml
[junit4] 2> 711123 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/.
[junit4] 2> 711123 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer New CoreContainer 86252176
[junit4] 2> 711123 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002]
[junit4] 2> 711123 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-002/lib
[junit4] 2> 711123 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 711124 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 711124 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 711124 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 711124 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 711124 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 711125 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/.
[junit4] 2> 711125 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 711125 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1
[junit4] 2> 711125 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 711126 INFO (coreLoadExecutor-2013-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_8A5E7E72F9D7974F-001/solr-instance-002/collection1'
[junit4] 2> 711126 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 711126 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 711126 INFO (coreLoadExecutor-2013-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 711126 INFO (coreLoadExecutor-2013-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 711130 INFO (coreLoadExecutor-2013-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 711132 INFO (coreLoadExecutor-2013-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 711136 INFO (coreLoadExecutor-2013-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 711139 INFO (coreLoadExecutor-2013-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 711140 INFO (coreLoadExecutor-2013-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 711141 INFO (coreLoadExecutor-2013-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_8A5E7E72F9D7974F-001/solr-instance-002/collection1'
[junit4] 2> 711141 INFO (coreLoadExecutor-2013-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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1
[junit4] 2> 711141 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 711141 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/]
[junit4] 2> 711142 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 711142 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data
[junit4] 2> 711142 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 711142 WARN (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 711142 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/index
[junit4] 2> 711142 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.32263961256201706]
[junit4] 2> 711142 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@77fc4a6f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55efbe28),segFN=segments_1,generation=1}
[junit4] 2> 711142 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 711143 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x: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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/
[junit4] 2> 711143 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 711144 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711145 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711145 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711146 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711146 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 711146 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=33.3779296875, floorSegmentMB=2.0009765625, forceMergeDeletesPctAllowed=20.52779558983096, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@77fc4a6f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55efbe28),segFN=segments_1,generation=1}
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@39f3ddc1[collection1] main
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/conf
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/conf}
[junit4] 2> 711147 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/conf
[junit4] 2> 711148 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 711149 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 711149 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39f3ddc1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 711149 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 711151 INFO (qtp1113726795-4366) [ x:collection1] 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> 711628 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@98f9518{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 711628 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=86252176
[junit4] 2> 711628 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@530df554
[junit4] 2> 711628 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] 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> 711628 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 711628 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 711628 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 711630 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 711630 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 711630 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 711630 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/index
[junit4] 2> 711630 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 711630 INFO (coreCloseExecutor-2019-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data
[junit4] 2> 711631 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@63580c3{/solr,null,UNAVAILABLE}
[junit4] 2> 711632 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 711632 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60bbff67{/solr,null,AVAILABLE}
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@16e38d7a{HTTP/1.1}{127.0.0.1:42682}
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server Started @713535ms
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=42682}
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002'
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 711633 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/solr.xml
[junit4] 2> 711636 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/.
[junit4] 2> 711637 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer New CoreContainer 2398067
[junit4] 2> 711637 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002]
[junit4] 2> 711637 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-002/lib
[junit4] 2> 711637 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 711638 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 711638 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 711638 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 711638 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 711638 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 711638 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/.
[junit4] 2> 711639 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 711639 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1
[junit4] 2> 711639 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 711639 INFO (coreLoadExecutor-2025-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_8A5E7E72F9D7974F-001/solr-instance-002/collection1'
[junit4] 2> 711639 INFO (coreLoadExecutor-2025-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 711639 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 711639 INFO (coreLoadExecutor-2025-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 711639 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 711643 INFO (coreLoadExecutor-2025-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 711645 INFO (coreLoadExecutor-2025-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 711650 INFO (coreLoadExecutor-2025-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 711652 INFO (coreLoadExecutor-2025-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 711653 INFO (coreLoadExecutor-2025-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 711654 INFO (coreLoadExecutor-2025-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_8A5E7E72F9D7974F-001/solr-instance-002/collection1'
[junit4] 2> 711654 INFO (coreLoadExecutor-2025-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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1
[junit4] 2> 711655 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.NRTCachingDirectoryFactory
[junit4] 2> 711658 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/]
[junit4] 2> 711658 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 711658 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data
[junit4] 2> 711659 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 711659 WARN (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 711659 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data/index
[junit4] 2> 711659 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=0.865234375, floorSegmentMB=1.12109375, forceMergeDeletesPctAllowed=20.89738707792231, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.32263961256201706
[junit4] 2> 711717 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f06c7a8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 711717 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 711729 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x: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_8A5E7E72F9D7974F-001/solr-instance-002/./collection1/data/
[junit4] 2> 711729 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 711731 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711732 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711732 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711733 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 711734 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 711734 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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> 711734 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 711734 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 711734 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 711734 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6474078823954923]
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f06c7a8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1050b971[collection1] main
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/conf
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/conf}
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-002/collection1/conf
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 711735 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 711736 INFO (coreLoadExecutor-2025-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 711736 INFO (searcherExecutor-2026-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1050b971[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 712141 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@bea6842{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 712141 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=64625306
[junit4] 2> 712142 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12882c43
[junit4] 2> 712142 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] 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> 712142 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 712142 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 712142 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 712143 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 712143 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 712143 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/index;done=false>>]
[junit4] 2> 712143 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/index
[junit4] 2> 712143 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data;done=false>>]
[junit4] 2> 712143 INFO (coreCloseExecutor-2030-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data
[junit4] 2> 712144 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@77690437{/solr,null,UNAVAILABLE}
[junit4] 2> 712145 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 712145 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@727ea95b{/solr,null,AVAILABLE}
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@710da050{HTTP/1.1}{127.0.0.1:55732}
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.e.j.s.Server Started @714048ms
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=55732}
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001'
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 712146 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/solr.xml
[junit4] 2> 712149 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/.
[junit4] 2> 712150 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer New CoreContainer 1072833044
[junit4] 2> 712150 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001]
[junit4] 2> 712150 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-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_8A5E7E72F9D7974F-001/solr-instance-001/lib
[junit4] 2> 712150 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 712150 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 712151 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 712151 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 712151 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 712151 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 712151 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/.
[junit4] 2> 712152 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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> 712152 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1
[junit4] 2> 712152 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 712152 INFO (coreLoadExecutor-2036-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_8A5E7E72F9D7974F-001/solr-instance-001/collection1'
[junit4] 2> 712152 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 712152 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 712152 INFO (coreLoadExecutor-2036-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 712152 INFO (coreLoadExecutor-2036-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 712157 INFO (coreLoadExecutor-2036-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 712159 INFO (coreLoadExecutor-2036-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 712163 INFO (coreLoadExecutor-2036-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 712166 INFO (coreLoadExecutor-2036-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 712168 INFO (coreLoadExecutor-2036-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 712169 INFO (coreLoadExecutor-2036-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_8A5E7E72F9D7974F-001/solr-instance-001/collection1'
[junit4] 2> 712169 INFO (coreLoadExecutor-2036-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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1
[junit4] 2> 712169 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.NRTCachingDirectoryFactory
[junit4] 2> 712169 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/]
[junit4] 2> 712169 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 712169 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/data
[junit4] 2> 712170 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 712170 WARN (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 712170 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/data/index
[junit4] 2> 712170 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=0.865234375, floorSegmentMB=1.12109375, forceMergeDeletesPctAllowed=20.89738707792231, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.32263961256201706
[junit4] 2> 712214 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2710a188; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 712214 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 712215 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x: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_8A5E7E72F9D7974F-001/solr-instance-001/./collection1/data/
[junit4] 2> 712215 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 712216 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 712216 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 712217 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 712217 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 712218 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 712218 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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> 712218 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 712218 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 712218 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 712219 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6474078823954923]
[junit4] 2> 712219 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-001/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2710a188; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 712219 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 712219 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@61395bc3[collection1] main
[junit4] 2> 712219 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/conf
[junit4] 2> 712219 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/conf}
[junit4] 2> 712219 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] 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_8A5E7E72F9D7974F-001/solr-instance-001/collection1/conf
[junit4] 2> 712220 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 712220 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 712220 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 712220 INFO (searcherExecutor-2037-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61395bc3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 712220 INFO (coreLoadExecutor-2036-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 712654 INFO (qtp1073193463-4426) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 712655 INFO (qtp1073193463-4426) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 712656 INFO (qtp214037171-4406) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 712657 INFO (qtp214037171-4406) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 712659 INFO (qtp214037171-4407) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 712694 INFO (qtp214037171-4407) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f06c7a8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8A5E7E72F9D7974F-001/solr-instance-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f06c7a8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 712694 INFO (qtp214037171-4407) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 712695 INFO (qtp214037171-4407) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@72cf8699[collection1] main
[junit4] 2> 712695 INFO (qtp214037171-4407) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 712695 INFO (searcherExecutor-2026-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72cf8699[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 712695 INFO (qtp214037171-4407) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 37
[junit4] 2> 712699 INFO (qtp1073193463-4427) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1
[junit4] 2> 712701 INFO (qtp1073193463-4428) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 712702 INFO (qtp1073193463-4426) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 712703 INFO (qtp1073193463-4427) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 712705 INFO (qtp1073193463-4428) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 712706 INFO (qtp1073193463-4426) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 712708 INFO (qtp1073193463-4427) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 712709 INFO (qtp1073193463-4428) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 712711 INFO (qtp1073193463-4426) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 712712 INFO (qtp1073193463-4427) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 712714 INFO (qtp1073193463-4428) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 712715 INFO (qtp1073193463-4426) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 712716 INFO (qtp1073193463-4427) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 712721 INFO (qtp1073193463-4428) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 712722 INFO (qtp1073193463-4426) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 712724 INFO (qtp1073193463-4427) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/upd
[...truncated too long message...]
rTest.testBasics-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d8edfb6),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d8edfb6),segFN=segments_j,generation=19}
[junit4] 2> 1161330 INFO (TEST-DirectUpdateHandlerTest.testBasics-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 19
[junit4] 2> 1161330 INFO (TEST-DirectUpdateHandlerTest.testBasics-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@61dedfe0[collection1] main
[junit4] 2> 1161331 INFO (TEST-DirectUpdateHandlerTest.testBasics-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1161331 INFO (searcherExecutor-4770-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61dedfe0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1161332 INFO (TEST-DirectUpdateHandlerTest.testBasics-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 204
[junit4] 2> 1161332 INFO (TEST-DirectUpdateHandlerTest.testBasics-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1161332 INFO (TEST-DirectUpdateHandlerTest.testBasics-seed#[8A5E7E72F9D7974F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBasics
[junit4] 2> 1161332 INFO (SUITE-DirectUpdateHandlerTest-seed#[8A5E7E72F9D7974F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1161333 INFO (SUITE-DirectUpdateHandlerTest-seed#[8A5E7E72F9D7974F]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=295630418
[junit4] 2> 1161333 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3ba3bcf8
[junit4] 2> 1161333 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=12,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=2,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=8,cumulative_deletesById=1,cumulative_deletesByQuery=4,cumulative_errors=0}
[junit4] 2> 1161333 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1161333 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1161333 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1161333 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1161340 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1161340 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001;done=false>>]
[junit4] 2> 1161340 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001
[junit4] 2> 1161340 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001/index;done=false>>]
[junit4] 2> 1161340 INFO (coreCloseExecutor-4774-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001/init-core-data-001/index
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_8A5E7E72F9D7974F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{}, sim=DefaultSimilarity, locale=id-ID, timezone=Africa/Lome
[junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=12,threads=1,free=248804016,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestMiniSolrCloudCluster, LeaderElectionTest, RegexBoostProcessorTest, SharedFSAutoReplicaFailoverTest, ForceLeaderTest, TestFieldTypeCollectionResource, StatsComponentTest, RecoveryZkTest, DistributedExpandComponentTest, TestJmxIntegration, TestSha256AuthenticationProvider, TestCopyFieldCollectionResource, TestStressReorder, TestSweetSpotSimilarityFactory, TestSolrDeletionPolicy1, TestReplicationHandlerBackup, TestManagedSchemaFieldResource, TestElisionMultitermQuery, TestStressRecovery, BlockJoinFacetSimpleTest, ScriptEngineTest, SolrCoreCheckLockOnStartupTest, HdfsUnloadDistributedZkTest, TestAuthorizationFramework, TestReRankQParserPlugin, LeaderInitiatedRecoveryOnCommitTest, TestSortingResponseWriter, TestJsonRequest, ShowFileRequestHandlerTest, FieldAnalysisRequestHandlerTest, FieldMutatingUpdateProcessorTest, TestManagedSynonymFilterFactory, SearchHandlerTest, TestConfigSetsAPI, TestSchemaNameResource, BinaryUpdateRequestHandlerTest, DateRangeFieldTest, DebugComponentTest, TestDistribIDF, TestExclusionRuleCollectionAccess, TestOverriddenPrefixQueryForCustomFieldType, TestCloudInspectUtil, TestReversedWildcardFilterFactory, TestSolrConfigHandler, AssignTest, TestJmxMonitoredMap, OverseerTaskQueueTest, TestXIncludeConfig, LukeRequestHandlerTest, ReplicationFactorTest, SpatialRPTFieldTypeTest, FacetPivotSmallTest, StatelessScriptUpdateProcessorFactoryTest, TestCloudManagedSchemaConcurrent, TestMaxScoreQueryParser, HighlighterMaxOffsetTest, DistributedQueryComponentCustomSortTest, RequestHandlersTest, TestRecovery, TestFreeTextSuggestions, HdfsRestartWhileUpdatingTest, TestAnalyzeInfixSuggestions, HdfsChaosMonkeyNothingIsSafeTest, SolrTestCaseJ4Test, TestPostingsSolrHighlighter, QueryResultKeyTest, TestNamedUpdateProcessors, TestCSVResponseWriter, SSLMigrationTest, DistributedFacetPivotSmallAdvancedTest, TestPerFieldSimilarityWithDefaultOverride, TestCollationField, TestTolerantSearch, FullSolrCloudDistribCmdsTest, TestSolrQueryParserResource, OverseerCollectionConfigSetProcessorTest, SimpleFacetsTest, TestTestInjection, DirectoryFactoryTest, DataDrivenBlockJoinTest, TestSchemaVersionResource, TestWriterPerf, TestSolrJ, TestMacros, TestHdfsUpdateLog, BadComponentTest, UpdateParamsTest, HardAutoCommitTest, DateMathParserTest, TestCoreDiscovery, SuggestComponentTest, BasicDistributedZk2Test, UnloadDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, TestRandomFaceting, TestZkChroot, TestRandomDVFaceting, AutoCommitTest, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestJoin, TestReload, DistributedTermsComponentTest, TestRangeQuery, TestSolr4Spatial, SolrCoreTest, SpellCheckComponentTest, TestGroupingSearch, BadIndexSchemaTest, TestSort, TestLazyCores, DirectUpdateHandlerTest]
[junit4] Completed [280/566 (2!)] on J2 in 14.50s, 7 tests, 1 failure <<< FAILURES!
[...truncated 902 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, 2 failures, 84 ignored (39 assumptions) [seed: 8A5E7E72F9D7974F]
Total time: 53 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.7.0_80) - Build # 9 -
Still Failing!
Posted by Michael McCandless <lu...@mikemccandless.com>.
OK I see what's happening: the test is getting MockRandomMergePolicy,
which will randomly force merge regardless of maxNumSegments ... it's
a test bug.
I'll fix, but no need to re-spin 5.5.0.
Mike McCandless
http://blog.mikemccandless.com
On Mon, Feb 15, 2016 at 4:54 AM, Michael McCandless
<lu...@mikemccandless.com> wrote:
> This looks like https://issues.apache.org/jira/browse/LUCENE-7008 all
> over again?
>
> We thought it was fixed in
> https://issues.apache.org/jira/browse/LUCENE-7010 but maybe not quite?
>
> Mike McCandless
>
> http://blog.mikemccandless.com
>
>
> On Sun, Feb 14, 2016 at 8:33 PM, Steve Rowe <sa...@gmail.com> wrote:
>> This reproduces for me on branch_5_5, branch_5x and master.
>>
>> --
>> Steve
>> www.lucidworks.com
>>
>>> On Feb 14, 2016, at 8:20 PM, Policeman Jenkins Server <je...@thetaphi.de> wrote:
>>>
>>> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/9/
>>> Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseSerialGC
>>>
>>> 1 tests failed.
>>> FAILED: org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded
>>>
>>> Error Message:
>>>
>>>
>>> Stack Trace:
>>> java.lang.AssertionError
>>> at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
>>> at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
>>> at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
>>> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
>>> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
>>> at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
>>> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>>> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>>> at java.lang.reflect.Method.invoke(Method.java:606)
>>> 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:745)
>>>
>>>
>>>
>>>
>>> Build Log:
>>> [...truncated 7718 lines...]
>>> [junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
>>> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=8A485FF0B12B3B8C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es -Dtests.timezone=Etc/GMT-13 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
>>> [junit4] FAILURE 1.49s J1 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
>>> [junit4] > Throwable #1: java.lang.AssertionError
>>> [junit4] > at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
>>> [junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
>>> [junit4] > at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
>>> [junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
>>> [junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
>>> [junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
>>> [junit4] > at java.lang.Thread.run(Thread.java:745)
>>> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {s=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{ndv=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=es, timezone=Etc/GMT-13
>>> [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=12,threads=1,free=446770288,total=507641856
>>> [junit4] 2> NOTE: All tests run in this JVM: [TestFieldCacheSortRandom, TestSortingMergePolicy]
>>> [junit4] Completed [3/25 (1!)] on J1 in 4.67s, 3 tests, 1 failure <<< FAILURES!
>>>
>>> [...truncated 79 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: 25 suites, 187 tests, 1 failure, 1 ignored (1 assumption) [seed: 8A485FF0B12B3B8C]
>>>
>>> Total time: 17 minutes 55 seconds
>>> Build step 'Invoke Ant' marked build as failure
>>> Archiving artifacts
>>> [WARNINGS] Skipping publisher since build result is FAILURE
>>> Recording test results
>>> Email was triggered for: Failure - Any
>>> Sending email for trigger: Failure - Any
>>>
>>>
>>>
>>> ---------------------------------------------------------------------
>>> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
>>> For additional commands, e-mail: dev-help@lucene.apache.org
>>
>>
>> ---------------------------------------------------------------------
>> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
>> For additional commands, e-mail: dev-help@lucene.apache.org
>>
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.7.0_80) - Build # 9 -
Still Failing!
Posted by Michael McCandless <lu...@mikemccandless.com>.
This looks like https://issues.apache.org/jira/browse/LUCENE-7008 all
over again?
We thought it was fixed in
https://issues.apache.org/jira/browse/LUCENE-7010 but maybe not quite?
Mike McCandless
http://blog.mikemccandless.com
On Sun, Feb 14, 2016 at 8:33 PM, Steve Rowe <sa...@gmail.com> wrote:
> This reproduces for me on branch_5_5, branch_5x and master.
>
> --
> Steve
> www.lucidworks.com
>
>> On Feb 14, 2016, at 8:20 PM, Policeman Jenkins Server <je...@thetaphi.de> wrote:
>>
>> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/9/
>> Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseSerialGC
>>
>> 1 tests failed.
>> FAILED: org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded
>>
>> Error Message:
>>
>>
>> Stack Trace:
>> java.lang.AssertionError
>> at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
>> at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
>> at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
>> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
>> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
>> at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
>> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>> at java.lang.reflect.Method.invoke(Method.java:606)
>> 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:745)
>>
>>
>>
>>
>> Build Log:
>> [...truncated 7718 lines...]
>> [junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
>> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=8A485FF0B12B3B8C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es -Dtests.timezone=Etc/GMT-13 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
>> [junit4] FAILURE 1.49s J1 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
>> [junit4] > Throwable #1: java.lang.AssertionError
>> [junit4] > at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
>> [junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
>> [junit4] > at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
>> [junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
>> [junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
>> [junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
>> [junit4] > at java.lang.Thread.run(Thread.java:745)
>> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {s=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{ndv=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=es, timezone=Etc/GMT-13
>> [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=12,threads=1,free=446770288,total=507641856
>> [junit4] 2> NOTE: All tests run in this JVM: [TestFieldCacheSortRandom, TestSortingMergePolicy]
>> [junit4] Completed [3/25 (1!)] on J1 in 4.67s, 3 tests, 1 failure <<< FAILURES!
>>
>> [...truncated 79 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: 25 suites, 187 tests, 1 failure, 1 ignored (1 assumption) [seed: 8A485FF0B12B3B8C]
>>
>> Total time: 17 minutes 55 seconds
>> Build step 'Invoke Ant' marked build as failure
>> Archiving artifacts
>> [WARNINGS] Skipping publisher since build result is FAILURE
>> Recording test results
>> Email was triggered for: Failure - Any
>> Sending email for trigger: Failure - Any
>>
>>
>>
>> ---------------------------------------------------------------------
>> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
>> For additional commands, e-mail: dev-help@lucene.apache.org
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
>
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
Re: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.7.0_80) - Build # 9 - Still Failing!
Posted by Steve Rowe <sa...@gmail.com>.
This reproduces for me on branch_5_5, branch_5x and master.
--
Steve
www.lucidworks.com
> On Feb 14, 2016, at 8:20 PM, Policeman Jenkins Server <je...@thetaphi.de> wrote:
>
> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/9/
> Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseSerialGC
>
> 1 tests failed.
> FAILED: org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded
>
> Error Message:
>
>
> Stack Trace:
> java.lang.AssertionError
> at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
> at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
> at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
> at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> 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:745)
>
>
>
>
> Build Log:
> [...truncated 7718 lines...]
> [junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=8A485FF0B12B3B8C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es -Dtests.timezone=Etc/GMT-13 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
> [junit4] FAILURE 1.49s J1 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
> [junit4] > Throwable #1: java.lang.AssertionError
> [junit4] > at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
> [junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
> [junit4] > at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
> [junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
> [junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
> [junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
> [junit4] > at java.lang.Thread.run(Thread.java:745)
> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {s=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{ndv=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=es, timezone=Etc/GMT-13
> [junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=12,threads=1,free=446770288,total=507641856
> [junit4] 2> NOTE: All tests run in this JVM: [TestFieldCacheSortRandom, TestSortingMergePolicy]
> [junit4] Completed [3/25 (1!)] on J1 in 4.67s, 3 tests, 1 failure <<< FAILURES!
>
> [...truncated 79 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: 25 suites, 187 tests, 1 failure, 1 ignored (1 assumption) [seed: 8A485FF0B12B3B8C]
>
> Total time: 17 minutes 55 seconds
> Build step 'Invoke Ant' marked build as failure
> Archiving artifacts
> [WARNINGS] Skipping publisher since build result is FAILURE
> Recording test results
> Email was triggered for: Failure - Any
> Sending email for trigger: Failure - Any
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org
[JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.7.0_80) - Build # 9 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/9/
Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.lucene.index.TestSortingMergePolicy.testForceMergeNotNeeded
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:745)
Build Log:
[...truncated 7718 lines...]
[junit4] Suite: org.apache.lucene.index.TestSortingMergePolicy
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSortingMergePolicy -Dtests.method=testForceMergeNotNeeded -Dtests.seed=8A485FF0B12B3B8C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es -Dtests.timezone=Etc/GMT-13 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.49s J1 | TestSortingMergePolicy.testForceMergeNotNeeded <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([8A485FF0B12B3B8C:966B27EA2A74D099]:0)
[junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase$1.merge(BaseMergePolicyTestCase.java:43)
[junit4] > at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:1931)
[junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1764)
[junit4] > at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1721)
[junit4] > at org.apache.lucene.index.BaseMergePolicyTestCase.testForceMergeNotNeeded(BaseMergePolicyTestCase.java:62)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {s=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{ndv=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=es, timezone=Etc/GMT-13
[junit4] 2> NOTE: Linux 3.19.0-47-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=12,threads=1,free=446770288,total=507641856
[junit4] 2> NOTE: All tests run in this JVM: [TestFieldCacheSortRandom, TestSortingMergePolicy]
[junit4] Completed [3/25 (1!)] on J1 in 4.67s, 3 tests, 1 failure <<< FAILURES!
[...truncated 79 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: 25 suites, 187 tests, 1 failure, 1 ignored (1 assumption) [seed: 8A485FF0B12B3B8C]
Total time: 17 minutes 55 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