Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/13839/ Java: 64bit/jdk1.9.0-ea-b78 -XX:-UseCompressedOops -XX:+UseSerialGC
4 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithKerberosAlt Error Message: 5 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithKerberosAlt: 1) Thread[id=10927, name=apacheds, state=WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 2) Thread[id=10930, name=ou=system.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 3) Thread[id=10931, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 4) Thread[id=10929, name=groupCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 5) Thread[id=10928, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithKerberosAlt: 1) Thread[id=10927, name=apacheds, state=WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 2) Thread[id=10930, name=ou=system.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 3) Thread[id=10931, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 4) Thread[id=10929, name=groupCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 5) Thread[id=10928, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) at __randomizedtesting.SeedInfo.seed([86EDE5B87248C7D]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithKerberosAlt Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=10927, name=apacheds, state=WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 2) Thread[id=10930, name=ou=system.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 3) Thread[id=10931, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 4) Thread[id=10929, name=groupCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 5) Thread[id=10928, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=10927, name=apacheds, state=WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 2) Thread[id=10930, name=ou=system.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 3) Thread[id=10931, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 4) Thread[id=10929, name=groupCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) 5) Thread[id=10928, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:746) at __randomizedtesting.SeedInfo.seed([86EDE5B87248C7D]:0) FAILED: org.apache.solr.cloud.TestSolrCloudWithKerberosAlt.testBasics Error Message: 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: 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 __randomizedtesting.SeedInfo.seed([86EDE5B87248C7D:35B67077BFCAD20D]:0) 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.TestSolrCloudWithKerberosAlt.setupMiniKdc(TestSolrCloudWithKerberosAlt.java:105) at org.apache.solr.cloud.TestSolrCloudWithKerberosAlt.setUp(TestSolrCloudWithKerberosAlt.java:94) at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:504) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:746) 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 FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: [index.20150905024313197, replication.properties, index.20150905024311335, index.properties] expected:<1> but was:<2> Stack Trace: java.lang.AssertionError: [index.20150905024313197, replication.properties, index.20150905024311335, index.properties] expected:<1> but was:<2> at __randomizedtesting.SeedInfo.seed([86EDE5B87248C7D:D3C5DE9D820CE5CE]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:818) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:785) 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:504) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:746) Build Log: [...truncated 10509 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/init-core-data-001 [junit4] 2> 978129 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater [junit4] 2> 978129 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/collection1 [junit4] 2> 978131 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 978132 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4cb4de69{/solr,null,AVAILABLE} [junit4] 2> 978139 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@70e8274f{HTTP/1.1}{127.0.0.1:51992} [junit4] 2> 978139 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server Started @979955ms [junit4] 2> 978139 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=51992} [junit4] 2> 978140 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@f2a0b8e [junit4] 2> 978140 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/' [junit4] 2> 978148 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/solr.xml [junit4] 2> 978151 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/. [junit4] 2> 978152 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer New CoreContainer 1441985452 [junit4] 2> 978152 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/] [junit4] 2> 978152 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/lib [junit4] 2> 978152 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/lib). [junit4] 2> 978156 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] 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> 978157 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 978157 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 978157 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 978157 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 978157 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 978157 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/. [junit4] 2> 978159 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/, dataDir=data/} [junit4] 2> 978159 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/ [junit4] 2> 978159 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 978159 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/' [junit4] 2> 978159 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0 [junit4] 2> 978160 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 978168 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 978170 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0 [junit4] 2> 978181 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 978181 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/conf/schema.xml [junit4] 2> 978183 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 978185 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 978186 INFO (coreLoadExecutor-2940-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.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/conf' [junit4] 2> 978186 INFO (coreLoadExecutor-2940-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/ [junit4] 2> 978186 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 978186 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/], dataDir=[null] [junit4] 2> 978186 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 978186 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/data [junit4] 2> 978186 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/data/index/ [junit4] 2> 978187 WARN (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 978187 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/data/index [junit4] 2> 978187 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=28.712890625, floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=4.533250671107635, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 978187 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@70ad5c03 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f44024c),segFN=segments_1,generation=1} [junit4] 2> 978187 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 978187 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.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/data/ [junit4] 2> 978188 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 978189 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978189 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978190 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978190 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978191 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 978191 INFO (coreLoadExecutor-2940-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> 978191 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 978191 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 978191 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 978191 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.563320489896796] [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@70ad5c03 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f44024c),segFN=segments_1,generation=1} [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@326f491a[collection1] main [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/conf [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/conf} [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-001/./collection1/conf [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 978192 INFO (searcherExecutor-2941-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@326f491a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 978192 INFO (coreLoadExecutor-2940-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 978661 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/collection1 [junit4] 2> 978663 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 978664 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@619220f0{/solr,null,AVAILABLE} [junit4] 2> 978664 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5e0c408e{HTTP/1.1}{127.0.0.1:36664} [junit4] 2> 978665 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server Started @980480ms [junit4] 2> 978665 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=36664} [junit4] 2> 978665 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@f2a0b8e [junit4] 2> 978665 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/' [junit4] 2> 978672 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/solr.xml [junit4] 2> 978674 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/. [junit4] 2> 978675 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer New CoreContainer 124320413 [junit4] 2> 978675 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/] [junit4] 2> 978675 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/lib [junit4] 2> 978675 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/lib). [junit4] 2> 978680 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] 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> 978680 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 978681 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 978681 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 978681 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 978681 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 978681 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/. [junit4] 2> 978682 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/, dataDir=data/} [junit4] 2> 978682 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/ [junit4] 2> 978682 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 978682 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/' [junit4] 2> 978682 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0 [junit4] 2> 978683 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 978691 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 978693 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0 [junit4] 2> 978698 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 978699 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf/schema.xml [junit4] 2> 978700 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 978702 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 978702 INFO (coreLoadExecutor-2950-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.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf' [junit4] 2> 978703 INFO (coreLoadExecutor-2950-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/ [junit4] 2> 978703 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 978703 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/], dataDir=[null] [junit4] 2> 978703 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 978703 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data [junit4] 2> 978703 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index/ [junit4] 2> 978703 WARN (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 978703 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index [junit4] 2> 978704 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=28.712890625, floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=4.533250671107635, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 978704 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2916272d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65e06b84),segFN=segments_1,generation=1} [junit4] 2> 978704 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 978704 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.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/ [junit4] 2> 978705 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 978706 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978707 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978707 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978707 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 978708 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 978708 INFO (coreLoadExecutor-2950-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> 978708 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 978708 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.563320489896796] [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2916272d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@65e06b84),segFN=segments_1,generation=1} [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6bad422f[collection1] main [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf} [junit4] 2> 978709 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf [junit4] 2> 978710 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 978711 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 978711 INFO (searcherExecutor-2951-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6bad422f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 978711 INFO (coreLoadExecutor-2950-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 978909 INFO (qtp1594798973-6621) [ 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> 979188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@5e0c408e{HTTP/1.1}{127.0.0.1:0} [junit4] 2> 979188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=124320413 [junit4] 2> 979188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@563548f9 [junit4] 2> 979189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.u.UpdateHandler 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> 979189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState [junit4] 2> 979189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 979189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser [junit4] 2> 979189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 979189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 979190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index;done=false>>] [junit4] 2> 979190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index [junit4] 2> 979190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data;done=false>>] [junit4] 2> 979190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data [junit4] 2> 979191 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@619220f0{/solr,null,UNAVAILABLE} [junit4] 2> 979192 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 979193 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f6c2ace{/solr,null,AVAILABLE} [junit4] 2> 979193 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@250d8166{HTTP/1.1}{127.0.0.1:57719} [junit4] 2> 979193 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server Started @981009ms [junit4] 2> 979194 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=57719} [junit4] 2> 979194 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@f2a0b8e [junit4] 2> 979194 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/' [junit4] 2> 979206 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/solr.xml [junit4] 2> 979211 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/. [junit4] 2> 979211 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer New CoreContainer 1237255029 [junit4] 2> 979211 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/] [junit4] 2> 979211 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/lib [junit4] 2> 979211 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/lib). [junit4] 2> 979219 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] 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> 979219 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 979220 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 979220 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 979220 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 979220 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 979221 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/. [junit4] 2> 979221 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/, dataDir=data/} [junit4] 2> 979221 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/ [junit4] 2> 979222 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 979222 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/' [junit4] 2> 979222 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0 [junit4] 2> 979223 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 979236 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 979239 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0 [junit4] 2> 979251 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 979251 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf/schema.xml [junit4] 2> 979253 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 979254 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 979255 INFO (coreLoadExecutor-2961-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.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf' [junit4] 2> 979255 INFO (coreLoadExecutor-2961-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/ [junit4] 2> 979255 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 979255 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/], dataDir=[null] [junit4] 2> 979255 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 979255 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data [junit4] 2> 979255 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index/ [junit4] 2> 979256 WARN (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 979256 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/index [junit4] 2> 979256 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=28.712890625, floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=4.533250671107635, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 979256 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@36b3f8b9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4715e485),segFN=segments_1,generation=1} [junit4] 2> 979256 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 979257 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.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/data/ [junit4] 2> 979257 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 979258 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 979259 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 979259 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 979260 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 979260 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 979260 INFO (coreLoadExecutor-2961-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> 979261 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 979261 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 979261 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 979261 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.563320489896796] [junit4] 2> 979261 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@36b3f8b9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4715e485),segFN=segments_1,generation=1} [junit4] 2> 979261 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 979261 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6610278b[collection1] main [junit4] 2> 979262 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf [junit4] 2> 979262 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf} [junit4] 2> 979262 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-002/./collection1/conf [junit4] 2> 979262 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 979262 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 979262 INFO (searcherExecutor-2962-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6610278b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 979262 INFO (coreLoadExecutor-2961-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 979724 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/collection1 [junit4] 2> 979745 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 979745 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@623e379{/solr,null,AVAILABLE} [junit4] 2> 979746 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78b9dd5f{HTTP/1.1}{127.0.0.1:34554} [junit4] 2> 979746 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.e.j.s.Server Started @981562ms [junit4] 2> 979746 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=34554} [junit4] 2> 979747 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@f2a0b8e [junit4] 2> 979747 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/' [junit4] 2> 979753 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/solr.xml [junit4] 2> 979756 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/. [junit4] 2> 979756 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer New CoreContainer 545600287 [junit4] 2> 979756 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/] [junit4] 2> 979756 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/lib [junit4] 2> 979756 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/lib). [junit4] 2> 979761 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] 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> 979761 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 979762 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 979762 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 979762 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 979762 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 979762 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/. [junit4] 2> 979763 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[86EDE5B87248C7D]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/./collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_86EDE5B87248C7D-001/solr-instance-003/. [...truncated too long message...] readPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > 3) Thread[id=10931, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > 4) Thread[id=10929, name=groupCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > 5) Thread[id=10928, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > at __randomizedtesting.SeedInfo.seed([86EDE5B87248C7D]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=10927, name=apacheds, state=WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at java.lang.Object.wait(Native Method) [junit4] > at java.lang.Object.wait(Object.java:516) [junit4] > at java.util.TimerThread.mainLoop(Timer.java:526) [junit4] > at java.util.TimerThread.run(Timer.java:505) [junit4] > 2) Thread[id=10930, name=ou=system.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > 3) Thread[id=10931, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > 4) Thread[id=10929, name=groupCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > 5) Thread[id=10928, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithKerberosAlt] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) [junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:746) [junit4] > at __randomizedtesting.SeedInfo.seed([86EDE5B87248C7D]:0) [junit4] Completed [454/537] on J2 in 25.47s, 1 test, 3 errors <<< FAILURES! [...truncated 264 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:785: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:729: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:59: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:233: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:524: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1452: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1006: There were test failures: 537 suites, 2124 tests, 2 suite-level errors, 1 error, 1 failure, 339 ignored (30 assumptions) Total time: 46 minutes 45 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-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org