Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/14425/ Java: 64bit/jdk1.9.0-ea-b82 -XX:+UseCompressedOops -XX:+UseG1GC
4 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.SaslZkACLProviderTest Error Message: 5 threads leaked from SUITE scope at org.apache.solr.cloud.SaslZkACLProviderTest: 1) Thread[id=6592, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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) 2) Thread[id=6591, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6593, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6590, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 5) Thread[id=6594, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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.SaslZkACLProviderTest: 1) Thread[id=6592, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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) 2) Thread[id=6591, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6593, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6590, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 5) Thread[id=6594, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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([2AB36C544E662FBB]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.SaslZkACLProviderTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=6592, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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) 2) Thread[id=6591, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6593, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6590, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 5) Thread[id=6594, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6592, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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) 2) Thread[id=6591, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6593, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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=6590, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:516) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) 5) Thread[id=6594, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] 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([2AB36C544E662FBB]:0) FAILED: org.apache.solr.cloud.SaslZkACLProviderTest.testSaslZkACLProvider Error Message: org.apache.directory.api.ldap.model.exception.LdapOtherException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) ' Stack Trace: java.lang.RuntimeException: org.apache.directory.api.ldap.model.exception.LdapOtherException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) ' at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:211) at org.apache.solr.cloud.SaslZkACLProviderTest.setUp(SaslZkACLProviderTest.java:81) at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:519) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:898) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:873) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820) 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:367) at java.lang.Thread.run(Thread.java:746) Caused by: org.apache.directory.api.ldap.model.exception.LdapOtherException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) ' at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84) at org.apache.directory.server.core.DefaultDirectoryService.initialize(DefaultDirectoryService.java:1808) at org.apache.directory.server.core.DefaultDirectoryService.startup(DefaultDirectoryService.java:1248) at org.apache.hadoop.minikdc.MiniKdc.initDirectoryService(MiniKdc.java:383) at org.apache.hadoop.minikdc.MiniKdc.start(MiniKdc.java:319) at org.apache.solr.cloud.SaslZkACLProviderTest$SaslZkTestServer.run(SaslZkACLProviderTest.java:204) ... 38 more Caused by: java.lang.RuntimeException: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) ' at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:226) at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79) ... 43 more Caused by: org.apache.directory.api.ldap.model.exception.LdapOtherException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) ' at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:84) at org.apache.directory.server.core.api.schema.SchemaPartition.doInit(SchemaPartition.java:219) ... 44 more Caused by: org.apache.directory.api.ldap.model.exception.LdapInvalidAttributeValueException: ERR_04447_CANNOT_NORMALIZE_VALUE Cannot normalize the wrapped value ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) ' at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:211) at org.apache.directory.api.ldap.model.entry.StringValue.<init>(StringValue.java:107) at org.apache.directory.api.ldap.model.entry.DefaultAttribute.<init>(DefaultAttribute.java:468) at org.apache.directory.api.ldap.model.entry.DefaultEntry.<init>(DefaultEntry.java:315) at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:517) at org.apache.directory.server.core.partition.ldif.LdifPartition.loadEntries(LdifPartition.java:549) at org.apache.directory.server.core.partition.ldif.LdifPartition.doInit(LdifPartition.java:164) at org.apache.directory.server.core.api.partition.AbstractPartition.initialize(AbstractPartition.java:79) ... 45 more Caused by: org.apache.directory.api.ldap.model.exception.LdapInvalidAttributeValueException: ERR_04473_NOT_VALID_VALUE Not a valid value '20090818022733Z' for the AttributeType 'ATTRIBUTE_TYPE ( 1.3.6.1.4.1.18060.0.4.1.2.35 NAME 'schemaModifyTimestamp' DESC time which schema was modified SUP modifyTimestamp EQUALITY generalizedTimeMatch ORDERING generalizedTimeOrderingMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.24 USAGE directoryOperation ) ' at org.apache.directory.api.ldap.model.entry.AbstractValue.apply(AbstractValue.java:204) ... 52 more FAILED: org.apache.solr.cloud.TestConfigSetsAPIExclusivity.testAPIExclusivity Error Message: Unexpected exception: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33277/solr: delete the configset time out:300s expected:<0> but was:<1> Stack Trace: java.lang.AssertionError: Unexpected exception: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33277/solr: delete the configset time out:300s expected:<0> but was:<1> at __randomizedtesting.SeedInfo.seed([2AB36C544E662FBB:57C675AF17C296F2]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.cloud.TestConfigSetsAPIExclusivity.testAPIExclusivity(TestConfigSetsAPIExclusivity.java:95) 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:519) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:873) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820) 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:367) at java.lang.Thread.run(Thread.java:746) Build Log: [...truncated 9912 lines...] [junit4] Suite: org.apache.solr.cloud.TestConfigSetsAPIExclusivity [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestConfigSetsAPIExclusivity_2AB36C544E662FBB-001/init-core-data-001 [junit4] 2> 363571 INFO (SUITE-TestConfigSetsAPIExclusivity-seed#[2AB36C544E662FBB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 363572 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAPIExclusivity [junit4] 2> 363573 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 363574 INFO (Thread-883) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 363574 INFO (Thread-883) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 363674 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.ZkTestServer start zk server on port:35799 [junit4] 2> 363674 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 363674 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 363676 INFO (zkCallback-354-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5bbbdce3 name:ZooKeeperConnection Watcher:127.0.0.1:35799 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 363676 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 363677 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 363677 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml [junit4] 2> 363678 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/clusterprops.json [junit4] 2> 363680 INFO (jetty-launcher-353-thread-1) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 363681 INFO (jetty-launcher-353-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@21d900f0{/solr,null,AVAILABLE} [junit4] 2> 363691 INFO (jetty-launcher-353-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@140ea3e8{SSL-http/1.1}{127.0.0.1:33277} [junit4] 2> 363691 INFO (jetty-launcher-353-thread-1) [ ] o.e.j.s.Server Started @365338ms [junit4] 2> 363691 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33277} [junit4] 2> 363691 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@f2a0b8e [junit4] 2> 363691 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestConfigSetsAPIExclusivity_2AB36C544E662FBB-001/tempDir-001/' [junit4] 2> 363699 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 363699 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 363701 INFO (zkCallback-355-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@61a06593 name:ZooKeeperConnection Watcher:127.0.0.1:35799/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 363701 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 363701 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 363701 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 363705 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestConfigSetsAPIExclusivity_2AB36C544E662FBB-001/tempDir-001/. [junit4] 2> 363705 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1624129940 [junit4] 2> 363705 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestConfigSetsAPIExclusivity_2AB36C544E662FBB-001/tempDir-001/] [junit4] 2> 363705 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestConfigSetsAPIExclusivity_2AB36C544E662FBB-001/tempDir-001/lib [junit4] 2> 363705 WARN (jetty-launcher-353-thread-1) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestConfigSetsAPIExclusivity_2AB36C544E662FBB-001/tempDir-001/lib). [junit4] 2> 363710 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : https,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 363711 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 363713 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 363713 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 363713 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35799/solr [junit4] 2> 363713 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 363713 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 363717 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 363717 INFO (zkCallback-357-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@226c50e2 name:ZooKeeperConnection Watcher:127.0.0.1:35799 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 363718 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 363718 INFO (jetty-launcher-353-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 363719 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 363720 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1050ad5a name:ZooKeeperConnection Watcher:127.0.0.1:35799/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 363720 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 363720 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 363721 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 363722 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 363723 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 363723 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 363725 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 363725 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 363726 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 363726 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 363727 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 363727 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33277_solr [junit4] 2> 363728 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:33277_solr [junit4] 2> 363728 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 363729 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 363729 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 363729 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94640808541356035-127.0.0.1:33277_solr-n_0000000000 [junit4] 2> 363730 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33277_solr [junit4] 2> 363730 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 363730 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.Overseer Overseer (id=94640808541356035-127.0.0.1:33277_solr-n_0000000000) starting [junit4] 2> 363731 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 363733 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 363733 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 363733 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 363734 INFO (OverseerStateUpdate-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 363741 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 363741 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 363741 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestConfigSetsAPIExclusivity_2AB36C544E662FBB-001/tempDir-001/. [junit4] 2> 363742 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.c.CoresLocator Found 0 core definitions [junit4] 2> 363742 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2 [junit4] 2> 363742 INFO (jetty-launcher-353-thread-1) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 363742 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 363743 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 363743 INFO (zkCallback-359-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56312962 name:ZooKeeperConnection Watcher:127.0.0.1:35799 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 363743 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 363744 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 363750 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 363751 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 363752 INFO (zkCallback-361-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@54552548 name:ZooKeeperConnection Watcher:127.0.0.1:35799/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 363752 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 363752 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 363757 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/grandBaseConfigSet1/solrconfig.xml [junit4] 2> 363758 INFO (TEST-TestConfigSetsAPIExclusivity.testAPIExclusivity-seed#[2AB36C544E662FBB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/grandBaseConfigSet1/schema.xml [junit4] 2> 363842 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363844 INFO (qtp464815017-2600) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=baseConfigSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363845 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363846 INFO (OverseerThreadFactory-1159-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363848 INFO (qtp464815017-2629) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=configSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363849 INFO (OverseerThreadFactory-1159-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/schema.xml to /configs/baseConfigSet1/schema.xml [junit4] 2> 363850 INFO (OverseerThreadFactory-1159-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/schema.xml [junit4] 2> 363850 INFO (qtp464815017-2598) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363852 INFO (OverseerThreadFactory-1159-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/solrconfig.xml to /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363852 INFO (OverseerThreadFactory-1159-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363854 INFO (OverseerThreadFactory-1159-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000000 complete, response:{} [junit4] 2> 363854 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000000 state SyncConnected [junit4] 2> 363856 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2} status=0 QTime=19 [junit4] 2> 363859 INFO (qtp464815017-2599) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363862 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000002 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363862 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000004 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363862 INFO (OverseerThreadFactory-1159-thread-2-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363862 INFO (OverseerThreadFactory-1159-thread-3-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363863 ERROR (OverseerThreadFactory-1159-thread-3-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: delete failed:org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:351) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:345) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:115) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363863 INFO (OverseerThreadFactory-1159-thread-2-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler no existing ConfigSet properties found [junit4] 2> 363864 INFO (OverseerThreadFactory-1159-thread-3-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000004 complete, response:{Operation delete caused exception:=org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1,exception={msg=ConfigSet does not exist to delete: configSet1,rspCode=400}} [junit4] 2> 363876 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000004 state SyncConnected [junit4] 2> 363877 INFO (OverseerThreadFactory-1159-thread-2-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000002 complete, response:{} [junit4] 2> 363878 INFO (qtp464815017-2629) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={name=configSet1&action=DELETE&wt=javabin&version=2} status=400 QTime=30 [junit4] 2> 363882 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000006 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363894 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363894 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=configSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363895 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000002 state SyncConnected [junit4] 2> 363895 INFO (qtp464815017-2600) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={name=baseConfigSet1&action=DELETE&wt=javabin&version=2} status=0 QTime=51 [junit4] 2> 363901 INFO (qtp464815017-2629) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=baseConfigSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363906 ERROR (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: create failed:org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.createConfigSet(OverseerConfigSetMessageHandler.java:294) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:112) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363907 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000006 complete, response:{Operation create caused exception:=org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1,exception={msg=Base ConfigSet does not exist: baseConfigSet1,rspCode=400}} [junit4] 2> 363907 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000006 state SyncConnected [junit4] 2> 363909 INFO (qtp464815017-2598) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2} status=400 QTime=58 [junit4] 2> 363909 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000008 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363909 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000010 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363910 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363910 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363910 ERROR (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: delete failed:org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:351) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:345) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:115) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363910 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000010 complete, response:{Operation delete caused exception:=org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1,exception={msg=ConfigSet does not exist to delete: configSet1,rspCode=400}} [junit4] 2> 363910 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/schema.xml to /configs/baseConfigSet1/schema.xml [junit4] 2> 363911 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/schema.xml [junit4] 2> 363912 INFO (qtp464815017-2600) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363912 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000010 state SyncConnected [junit4] 2> 363913 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/solrconfig.xml to /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363914 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363914 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={name=configSet1&action=DELETE&wt=javabin&version=2} status=400 QTime=20 [junit4] 2> 363915 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000008 complete, response:{} [junit4] 2> 363915 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000012 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363915 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363916 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler no existing ConfigSet properties found [junit4] 2> 363920 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000008 state SyncConnected [junit4] 2> 363920 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000012 complete, response:{} [junit4] 2> 363921 INFO (qtp464815017-2599) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2} status=0 QTime=61 [junit4] 2> 363921 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000012 state SyncConnected [junit4] 2> 363923 INFO (qtp464815017-2598) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=configSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363923 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363926 INFO (qtp464815017-2629) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={name=baseConfigSet1&action=DELETE&wt=javabin&version=2} status=0 QTime=25 [junit4] 2> 363929 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000014 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363929 INFO (qtp464815017-2599) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=baseConfigSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363929 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363930 ERROR (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: create failed:org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.createConfigSet(OverseerConfigSetMessageHandler.java:294) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:112) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363930 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000014 complete, response:{Operation create caused exception:=org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1,exception={msg=Base ConfigSet does not exist: baseConfigSet1,rspCode=400}} [junit4] 2> 363930 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000014 state SyncConnected [junit4] 2> 363932 INFO (qtp464815017-2600) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2} status=400 QTime=19 [junit4] 2> 363933 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000016 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363933 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000017 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363933 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363933 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363933 ERROR (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: delete failed:org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:351) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:345) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:115) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363934 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000016 complete, response:{Operation delete caused exception:=org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1,exception={msg=ConfigSet does not exist to delete: configSet1,rspCode=400}} [junit4] 2> 363934 INFO (qtp464815017-2629) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363934 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000016 state SyncConnected [junit4] 2> 363934 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/schema.xml to /configs/baseConfigSet1/schema.xml [junit4] 2> 363935 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/schema.xml [junit4] 2> 363936 INFO (qtp464815017-2598) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={name=configSet1&action=DELETE&wt=javabin&version=2} status=400 QTime=13 [junit4] 2> 363936 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/solrconfig.xml to /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363936 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363938 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000017 complete, response:{} [junit4] 2> 363938 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000017 state SyncConnected [junit4] 2> 363938 INFO (qtp464815017-2600) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=configSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363939 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2} status=0 QTime=16 [junit4] 2> 363941 INFO (qtp464815017-2598) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363942 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000020 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363942 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000024 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363942 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363942 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"configSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363943 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler no existing ConfigSet properties found [junit4] 2> 363943 ERROR (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: delete failed:org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:351) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.deleteConfigSet(OverseerConfigSetMessageHandler.java:345) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:115) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363943 INFO (OverseerThreadFactory-1159-thread-5-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000024 complete, response:{Operation delete caused exception:=org.apache.solr.common.SolrException: ConfigSet does not exist to delete: configSet1,exception={msg=ConfigSet does not exist to delete: configSet1,rspCode=400}} [junit4] 2> 363945 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000020 complete, response:{} [junit4] 2> 363945 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000022 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363945 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363946 ERROR (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: create failed:org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.createConfigSet(OverseerConfigSetMessageHandler.java:294) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:112) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363946 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000020 state SyncConnected [junit4] 2> 363947 INFO (qtp464815017-2599) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={name=baseConfigSet1&action=DELETE&wt=javabin&version=2} status=0 QTime=17 [junit4] 2> 363947 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000022 complete, response:{Operation create caused exception:=org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1,exception={msg=Base ConfigSet does not exist: baseConfigSet1,rspCode=400}} [junit4] 2> 363948 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000022 state SyncConnected [junit4] 2> 363950 INFO (qtp464815017-2629) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2} status=400 QTime=15 [junit4] 2> 363950 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000025 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363950 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "baseConfigSet":"grandBaseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363950 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :delete with params name=baseConfigSet1&action=DELETE&wt=javabin&version=2 [junit4] 2> 363952 INFO (qtp464815017-2599) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363953 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/schema.xml to /configs/baseConfigSet1/schema.xml [junit4] 2> 363954 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/schema.xml [junit4] 2> 363956 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/grandBaseConfigSet1/solrconfig.xml to /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363957 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.c.SolrZkClient makePath: /configs/baseConfigSet1/solrconfig.xml [junit4] 2> 363959 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000025 complete, response:{} [junit4] 2> 363960 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000025 state SyncConnected [junit4] 2> 363961 INFO (qtp464815017-2598) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2} status=0 QTime=20 [junit4] 2> 363962 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000028 message:{ [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363962 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : delete , { [junit4] 2> "name":"baseConfigSet1", [junit4] 2> "operation":"configsets:delete"} [junit4] 2> 363963 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler no existing ConfigSet properties found [junit4] 2> 363963 INFO (qtp464815017-2629) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=grandBaseConfigSet1&name=baseConfigSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363967 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000028 complete, response:{} [junit4] 2> 363968 INFO (OverseerCollectionConfigSetProcessor-94640808541356035-127.0.0.1:33277_solr-n_0000000000) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000029 message:{ [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363969 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , { [junit4] 2> "name":"configSet1", [junit4] 2> "baseConfigSet":"baseConfigSet1", [junit4] 2> "operation":"configsets:create"} [junit4] 2> 363970 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000028 state SyncConnected [junit4] 2> 363971 ERROR (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerConfigSetMessageHandler ConfigSet: configSet1 operation: create failed:org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1 [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.createConfigSet(OverseerConfigSetMessageHandler.java:294) [junit4] 2> at org.apache.solr.cloud.OverseerConfigSetMessageHandler.processMessage(OverseerConfigSetMessageHandler.java:112) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:434) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:746) [junit4] 2> [junit4] 2> 363971 INFO (OverseerThreadFactory-1159-thread-4-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskProcessor Overseer ConfigSet Message Handler: Message id:/overseer/collection-queue-work/qn-0000000029 complete, response:{Operation create caused exception:=org.apache.solr.common.SolrException: Base ConfigSet does not exist: baseConfigSet1,exception={msg=Base ConfigSet does not exist: baseConfigSet1,rspCode=400}} [junit4] 2> 363972 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={name=baseConfigSet1&action=DELETE&wt=javabin&version=2} status=0 QTime=21 [junit4] 2> 363975 INFO (zkCallback-358-thread-1-processing-n:127.0.0.1:33277_solr) [n:127.0.0.1:33277_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000029 state SyncConnected [junit4] 2> 363977 INFO (qtp464815017-2599) [n:127.0.0.1:33277_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/configs params={baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2} status=400 QTime=24 [junit4] 2> 363979 INFO (qtp464815017-2598) [n:127.0.0.1:33277_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=baseConfigSet1&name=configSet1&action=CREATE&wt=javabin&version=2 [junit4] 2> 363980 INFO (qtp464815017-2631) [n:127.0.0.1:33277_solr [...truncated too long message...] ata, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] [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=6593, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] [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=6590, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] [junit4] > at java.lang.Object.wait(Native Method) [junit4] > at java.lang.Object.wait(Object.java:516) [junit4] > at java.util.TimerThread.mainLoop(Timer.java:526) [junit4] > at java.util.TimerThread.run(Timer.java:505) [junit4] > 5) Thread[id=6594, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] [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([2AB36C544E662FBB]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=6592, name=ou=system.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] [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] > 2) Thread[id=6591, name=kdcReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] [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=6593, name=groupCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] [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=6590, name=apacheds, state=WAITING, group=TGRP-SaslZkACLProviderTest] [junit4] > at java.lang.Object.wait(Native Method) [junit4] > at java.lang.Object.wait(Object.java:516) [junit4] > at java.util.TimerThread.mainLoop(Timer.java:526) [junit4] > at java.util.TimerThread.run(Timer.java:505) [junit4] > 5) Thread[id=6594, name=changePwdReplayCache.data, state=TIMED_WAITING, group=TGRP-SaslZkACLProviderTest] [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([2AB36C544E662FBB]:0) [junit4] Completed [193/547] on J0 in 24.99s, 1 test, 3 errors <<< FAILURES! [...truncated 1085 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:775: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:719: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:516: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1432: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:987: There were test failures: 547 suites (8 ignored), 2147 tests, 2 suite-level errors, 1 error, 1 failure, 1039 ignored (24 assumptions) [seed: 2AB36C544E662FBB] Total time: 38 minutes 13 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org