Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4843/ Java: 64bit/jdk1.8.0_45 -XX:-UseCompressedOops -XX:+UseG1GC
5 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CdcrRequestHandlerTest Error Message: Some resources were not closed, shutdown, or released. Stack Trace: java.lang.AssertionError: Some resources were not closed, shutdown, or released. at __randomizedtesting.SeedInfo.seed([46273C4DCB58A707]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:234) 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:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CdcrRequestHandlerTest Error Message: 5 threads leaked from SUITE scope at org.apache.solr.cloud.CdcrRequestHandlerTest: 1) Thread[id=563, name=zkCallback-101-thread-1, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 2) Thread[id=586, name=zkCallback-101-thread-2, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 3) Thread[id=561, name=zkCallback-72-thread-3-processing-{node_name=127.0.0.1:52489_jn%2Fbc}-SendThread(127.0.0.1:52482), state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994) 4) Thread[id=587, name=zkCallback-101-thread-3, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 5) Thread[id=562, name=zkCallback-72-thread-3-processing-{node_name=127.0.0.1:52489_jn%2Fbc}-EventThread, state=WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.CdcrRequestHandlerTest: 1) Thread[id=563, name=zkCallback-101-thread-1, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 2) Thread[id=586, name=zkCallback-101-thread-2, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 3) Thread[id=561, name=zkCallback-72-thread-3-processing-{node_name=127.0.0.1:52489_jn%2Fbc}-SendThread(127.0.0.1:52482), state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994) 4) Thread[id=587, name=zkCallback-101-thread-3, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 5) Thread[id=562, name=zkCallback-72-thread-3-processing-{node_name=127.0.0.1:52489_jn%2Fbc}-EventThread, state=WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494) at __randomizedtesting.SeedInfo.seed([46273C4DCB58A707]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CdcrRequestHandlerTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=563, name=zkCallback-101-thread-1, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 2) Thread[id=586, name=zkCallback-101-thread-2, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 3) Thread[id=561, name=zkCallback-72-thread-3-processing-{node_name=127.0.0.1:52489_jn%2Fbc}-SendThread(127.0.0.1:52482), state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994) 4) Thread[id=587, name=zkCallback-101-thread-3, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=563, name=zkCallback-101-thread-1, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 2) Thread[id=586, name=zkCallback-101-thread-2, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) 3) Thread[id=561, name=zkCallback-72-thread-3-processing-{node_name=127.0.0.1:52489_jn%2Fbc}-SendThread(127.0.0.1:52482), state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994) 4) Thread[id=587, name=zkCallback-101-thread-3, state=TIMED_WAITING, group=TGRP-CdcrRequestHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066) 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:745) at __randomizedtesting.SeedInfo.seed([46273C4DCB58A707]:0) FAILED: org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest Error Message: There are still nodes recoverying - waited for 330 seconds Stack Trace: java.lang.AssertionError: There are still nodes recoverying - waited for 330 seconds at __randomizedtesting.SeedInfo.seed([46273C4DCB58A707:E16384E9A6E3B4BE]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:172) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:128) at org.apache.solr.cloud.BaseCdcrDistributedZkTest.waitForRecoveriesToFinish(BaseCdcrDistributedZkTest.java:419) at org.apache.solr.cloud.BaseCdcrDistributedZkTest.restartServer(BaseCdcrDistributedZkTest.java:460) at org.apache.solr.cloud.CdcrReplicationHandlerTest.doTestPartialReplication(CdcrReplicationHandlerTest.java:109) at org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest(CdcrReplicationHandlerTest.java:51) 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:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.CdcrRequestHandlerTest.doTest Error Message: expected:<st[art]ed> but was:<st[opp]ed> Stack Trace: org.junit.ComparisonFailure: expected:<st[art]ed> but was:<st[opp]ed> at __randomizedtesting.SeedInfo.seed([46273C4DCB58A707:E16384E9A6E3B4BE]:0) at org.junit.Assert.assertEquals(Assert.java:125) at org.junit.Assert.assertEquals(Assert.java:147) at org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertState(BaseCdcrDistributedZkTest.java:255) at org.apache.solr.cloud.CdcrRequestHandlerTest.doTestLifeCycleActions(CdcrRequestHandlerTest.java:55) at org.apache.solr.cloud.CdcrRequestHandlerTest.doTest(CdcrRequestHandlerTest.java:39) 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:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9263 lines...] [junit4] Suite: org.apache.solr.cloud.CdcrRequestHandlerTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\init-core-data-001 [junit4] 2> 87720 T392 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 87720 T392 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /jn/bc [junit4] 2> 87725 T392 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 2> 87733 T393 oasc.ZkTestServer$2$1.setClientPort client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 87733 T393 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 87812 T392 oasc.ZkTestServer.run start zk server on port:52482 [junit4] 2> 87892 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-cdcr.xml to /configs/conf1/solrconfig.xml [junit4] 2> 87900 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml to /configs/conf1/schema.xml [junit4] 2> 87904 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 87906 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 87913 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 87915 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 87917 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 87921 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 87924 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 87926 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 87930 T392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 87935 T392 oas.SolrTestCaseJ4.setUp ###Starting doTest [junit4] 2> 88184 T392 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1 [junit4] 2> 88187 T392 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 88193 T392 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@633d5f65{/jn/bc,null,AVAILABLE} [junit4] 2> 88196 T392 oejs.AbstractConnector.doStart Started ServerConnector@3d958919{HTTP/1.1}{127.0.0.1:52489} [junit4] 2> 88196 T392 oejs.Server.doStart Started @94646ms [junit4] 2> 88196 T392 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {shards=shard1, hostContext=/jn/bc, hostPort=52489, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores} [junit4] 2> 88197 T392 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 88197 T392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\' [junit4] 2> 88225 T392 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\solr.xml [junit4] 2> 88231 T392 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores [junit4] 2> 88231 T392 oasc.CoreContainer.<init> New CoreContainer 973605993 [junit4] 2> 88231 T392 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\] [junit4] 2> 88231 T392 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\lib [junit4] 2> 88232 T392 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\lib). [junit4] 2> 88239 T392 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 88241 T392 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 88242 T392 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 88242 T392 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 88242 T392 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 88243 T392 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52482/solr [junit4] 2> 88243 T392 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 88284 T392 n:127.0.0.1:52489_jn%2Fbc oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52489_jn%2Fbc [junit4] 2> 88293 T392 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 88296 T392 n:127.0.0.1:52489_jn%2Fbc oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:52489_jn%2Fbc [junit4] 2> 88297 T392 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer.start Overseer (id=93870968331894787-127.0.0.1:52489_jn%2Fbc-n_0000000000) starting [junit4] 2> 88308 T392 n:127.0.0.1:52489_jn%2Fbc oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 88309 T422 n:127.0.0.1:52489_jn%2Fbc oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 88310 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 88317 T392 n:127.0.0.1:52489_jn%2Fbc oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 88318 T392 n:127.0.0.1:52489_jn%2Fbc oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 88319 T392 n:127.0.0.1:52489_jn%2Fbc oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores [junit4] 2> 88320 T392 n:127.0.0.1:52489_jn%2Fbc oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1, collection=tmp_collection, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 88320 T392 n:127.0.0.1:52489_jn%2Fbc oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\ [junit4] 2> 88321 T392 n:127.0.0.1:52489_jn%2Fbc oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 88325 T424 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=tmp_collection [junit4] 2> 88325 T424 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 88326 T424 n:127.0.0.1:52489_jn%2Fbc oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 88326 T420 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 88328 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52489/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52489_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 88328 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52489/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52489_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 88328 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ClusterStateMutator.createCollection building a new cName: tmp_collection [junit4] 2> 88328 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 89182 T424 n:127.0.0.1:52489_jn%2Fbc oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 89182 T424 n:127.0.0.1:52489_jn%2Fbc oasc.ZkController.createCollectionZkNode Check for collection zkNode:tmp_collection [junit4] 2> 89182 T424 n:127.0.0.1:52489_jn%2Fbc oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:tmp_collection [junit4] 2> 89183 T424 n:127.0.0.1:52489_jn%2Fbc oasc.ZkController.getConfName Looking for collection configName [junit4] 2> 89183 T424 n:127.0.0.1:52489_jn%2Fbc oasc.ZkController.getConfName Only one config set found in zk - using it:conf1 [junit4] 2> 89186 T424 n:127.0.0.1:52489_jn%2Fbc oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\' [junit4] 2> 89202 T424 n:127.0.0.1:52489_jn%2Fbc oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 89208 T424 n:127.0.0.1:52489_jn%2Fbc oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 89212 T424 n:127.0.0.1:52489_jn%2Fbc oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 89218 T424 n:127.0.0.1:52489_jn%2Fbc oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 89219 T424 n:127.0.0.1:52489_jn%2Fbc oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 89224 T424 n:127.0.0.1:52489_jn%2Fbc oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 89291 T424 n:127.0.0.1:52489_jn%2Fbc oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 89292 T424 n:127.0.0.1:52489_jn%2Fbc oass.IndexSchema.readSchema unique key field: id [junit4] 2> 89293 T424 n:127.0.0.1:52489_jn%2Fbc oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 89295 T424 n:127.0.0.1:52489_jn%2Fbc oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 89315 T424 n:127.0.0.1:52489_jn%2Fbc oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection tmp_collection [junit4] 2> 89315 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 89315 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\], dataDir=[null] [junit4] 2> 89315 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@359559e0 [junit4] 2> 89316 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\data\ [junit4] 2> 89316 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\data\index/ [junit4] 2> 89316 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 89316 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-001\cores\collection1\data\index [junit4] 2> 89316 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=2.896484375, floorSegmentMB=2.048828125, forceMergeDeletesPctAllowed=16.24685956030096, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8190586478696402 [junit4] 2> 89317 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@551145c2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29a8b9cc),segFN=segments_1,generation=1} [junit4] 2> 89317 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 89321 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "cdcr-processor-chain" [junit4] 2> 89352 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 89356 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 89357 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 89358 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 89360 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 89361 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.PluginBag.createPlugin requestHandler : '/replication' created with startup=lazy [junit4] 2> 89392 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 89392 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 89393 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin,/admin/threads,/admin/ping,/cdcr,/update,/admin/file [junit4] 2> 89393 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 89414 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 89414 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 89416 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 89416 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 89419 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 89420 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@551145c2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29a8b9cc),segFN=segments_1,generation=1} [junit4] 2> 89420 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 89421 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@6eaf94e2[collection1] main [junit4] 2> 89421 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 89421 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 89421 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.VersionInfo.getMaxVersionFromIndex WARN No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 89421 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion WARN Could not find max version in index or recent updates, using new clock 1501935495076118528 [junit4] 2> 89422 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 1 ms to seed version buckets with highest version 1501935495076118528 [junit4] 2> 89423 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 89423 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 89424 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 89424 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 89435 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 89436 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 89436 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 89446 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oash.CdcrBufferStateManager.createStateNode Created znode /collections/tmp_collection/cdcr/state/buffer [junit4] 2> 89453 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oash.CdcrProcessStateManager.createStateNode Created znode /collections/tmp_collection/cdcr/state/process [junit4] 2> 89493 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/" [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore [junit4] 2> 89493 T425 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6eaf94e2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 89493 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 89493 T424 n:127.0.0.1:52489_jn%2Fbc x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 89495 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52489/jn/bc collection:tmp_collection shard:shard2 [junit4] 2> 89496 T392 n:127.0.0.1:52489_jn%2Fbc oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1 [junit4] 2> 89496 T392 n:127.0.0.1:52489_jn%2Fbc oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 89505 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 89509 T420 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 89509 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 89509 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C82 name=collection1 org.apache.solr.core.SolrCore@3823c5f0 url=http://127.0.0.1:52489/jn/bc/collection1 node=127.0.0.1:52489_jn%2Fbc C82_STATE=coll:tmp_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:52489/jn/bc, node_name=127.0.0.1:52489_jn%2Fbc, state=down} [junit4] 2> 89509 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 C82 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52489/jn/bc/collection1/ [junit4] 2> 89509 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 C82 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 89509 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection"} current state version: 1 [junit4] 2> 89509 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 C82 oasc.SyncStrategy.syncToMe http://127.0.0.1:52489/jn/bc/collection1/ has no replicas [junit4] 2> 89509 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52489/jn/bc/collection1/ shard2 [junit4] 2> 89514 T420 n:127.0.0.1:52489_jn%2Fbc oash.CdcrLeaderStateManager$LeaderStateWatcher.process Received new leader state @ tmp_collection:shard2 [junit4] 2> 89520 T420 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 89522 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "base_url":"http://127.0.0.1:52489/jn/bc", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 89616 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ZkController.register We are http://127.0.0.1:52489/jn/bc/collection1/ and leader is http://127.0.0.1:52489/jn/bc/collection1/ [junit4] 2> 89616 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52489/jn/bc [junit4] 2> 89616 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 89616 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=tmp_collection [junit4] 2> 89616 T429 n:127.0.0.1:52489_jn%2Fbc c:tmp_collection s:shard2 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 89618 T420 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 89619 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52489/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52489_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 89620 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52489/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52489_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 89761 T392 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1 [junit4] 2> 89763 T392 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 89767 T392 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@c05f46d{/jn/bc,null,AVAILABLE} [junit4] 2> 89768 T392 oejs.AbstractConnector.doStart Started ServerConnector@124a1333{HTTP/1.1}{127.0.0.1:52498} [junit4] 2> 89768 T392 oejs.Server.doStart Started @96484ms [junit4] 2> 89768 T392 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {shards=shard2, hostContext=/jn/bc, hostPort=52498, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores} [junit4] 2> 89768 T392 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 89768 T392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\' [junit4] 2> 89789 T392 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\solr.xml [junit4] 2> 89796 T392 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores [junit4] 2> 89796 T392 oasc.CoreContainer.<init> New CoreContainer 1469202968 [junit4] 2> 89796 T392 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\] [junit4] 2> 89797 T392 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\lib [junit4] 2> 89797 T392 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\lib). [junit4] 2> 89804 T392 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 89807 T392 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 89808 T392 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 89808 T392 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 89808 T392 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 89808 T392 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52482/solr [junit4] 2> 89808 T392 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 90761 T392 n:127.0.0.1:52498_jn%2Fbc oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52498_jn%2Fbc [junit4] 2> 90766 T392 n:127.0.0.1:52498_jn%2Fbc oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 90769 T392 n:127.0.0.1:52498_jn%2Fbc oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 90770 T392 n:127.0.0.1:52498_jn%2Fbc oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 90771 T392 n:127.0.0.1:52498_jn%2Fbc oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores [junit4] 2> 90772 T392 n:127.0.0.1:52498_jn%2Fbc oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1, collection=tmp_collection, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 90773 T392 n:127.0.0.1:52498_jn%2Fbc oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\ [junit4] 2> 90773 T392 n:127.0.0.1:52498_jn%2Fbc oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 90776 T447 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=tmp_collection [junit4] 2> 90776 T447 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 90777 T447 n:127.0.0.1:52498_jn%2Fbc oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 90777 T420 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 90779 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52498/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52498_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 90779 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52498/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52498_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 90779 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 90779 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 91718 T447 n:127.0.0.1:52498_jn%2Fbc oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 91718 T447 n:127.0.0.1:52498_jn%2Fbc oasc.ZkController.createCollectionZkNode Check for collection zkNode:tmp_collection [junit4] 2> 91718 T447 n:127.0.0.1:52498_jn%2Fbc oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 91720 T447 n:127.0.0.1:52498_jn%2Fbc oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\' [junit4] 2> 91735 T447 n:127.0.0.1:52498_jn%2Fbc oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 91742 T447 n:127.0.0.1:52498_jn%2Fbc oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 91744 T447 n:127.0.0.1:52498_jn%2Fbc oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 91751 T447 n:127.0.0.1:52498_jn%2Fbc oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 91752 T447 n:127.0.0.1:52498_jn%2Fbc oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 91757 T447 n:127.0.0.1:52498_jn%2Fbc oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 91846 T447 n:127.0.0.1:52498_jn%2Fbc oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 91847 T447 n:127.0.0.1:52498_jn%2Fbc oass.IndexSchema.readSchema unique key field: id [junit4] 2> 91848 T447 n:127.0.0.1:52498_jn%2Fbc oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 91869 T447 n:127.0.0.1:52498_jn%2Fbc oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 91904 T447 n:127.0.0.1:52498_jn%2Fbc oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection tmp_collection [junit4] 2> 91905 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 91905 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\], dataDir=[null] [junit4] 2> 91905 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@359559e0 [junit4] 2> 91906 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\data\ [junit4] 2> 91906 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\data\index/ [junit4] 2> 91906 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 91907 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-002\cores\collection1\data\index [junit4] 2> 91907 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=2.896484375, floorSegmentMB=2.048828125, forceMergeDeletesPctAllowed=16.24685956030096, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8190586478696402 [junit4] 2> 91907 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2f6ee954 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4109d5b3),segFN=segments_1,generation=1} [junit4] 2> 91908 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 91914 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "cdcr-processor-chain" [junit4] 2> 91915 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 91919 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 91920 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 91921 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 91922 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 91923 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.PluginBag.createPlugin requestHandler : '/replication' created with startup=lazy [junit4] 2> 91930 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 91931 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 91931 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin,/admin/threads,/admin/ping,/cdcr,/update,/admin/file [junit4] 2> 91931 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 91932 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 91932 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 91934 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 91934 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 91936 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 91936 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2f6ee954 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4109d5b3),segFN=segments_1,generation=1} [junit4] 2> 91936 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 91936 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@3e6f1905[collection1] main [junit4] 2> 91937 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 91937 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 91937 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.VersionInfo.getMaxVersionFromIndex WARN No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 91937 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion WARN Could not find max version in index or recent updates, using new clock 1501935497714335744 [junit4] 2> 91938 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 1 ms to seed version buckets with highest version 1501935497714335744 [junit4] 2> 91941 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 91941 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 91941 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 91941 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 91944 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 91944 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 91944 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 91962 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasha.AdminHandlers.inform WARN <requestHandler name="/admin/" [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore [junit4] 2> 91963 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 91964 T448 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3e6f1905[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 91965 T447 n:127.0.0.1:52498_jn%2Fbc x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 91966 T392 n:127.0.0.1:52498_jn%2Fbc oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1 [junit4] 2> 91966 T392 n:127.0.0.1:52498_jn%2Fbc oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 91997 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52498/jn/bc collection:tmp_collection shard:shard1 [junit4] 2> 92000 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 92001 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 92001 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C83 name=collection1 org.apache.solr.core.SolrCore@4235f584 url=http://127.0.0.1:52498/jn/bc/collection1 node=127.0.0.1:52498_jn%2Fbc C83_STATE=coll:tmp_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:52498/jn/bc, node_name=127.0.0.1:52498_jn%2Fbc, state=down} [junit4] 2> 92002 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 C83 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52498/jn/bc/collection1/ [junit4] 2> 92002 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 C83 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 92002 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 C83 oasc.SyncStrategy.syncToMe http://127.0.0.1:52498/jn/bc/collection1/ has no replicas [junit4] 2> 92002 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52498/jn/bc/collection1/ shard1 [junit4] 2> 92003 T446 n:127.0.0.1:52498_jn%2Fbc oash.CdcrLeaderStateManager$LeaderStateWatcher.process Received new leader state @ tmp_collection:shard1 [junit4] 2> 92011 T420 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 92012 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection"} current state version: 4 [junit4] 2> 92014 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "base_url":"http://127.0.0.1:52498/jn/bc", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 92080 T420 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 92134 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ZkController.register We are http://127.0.0.1:52498/jn/bc/collection1/ and leader is http://127.0.0.1:52498/jn/bc/collection1/ [junit4] 2> 92134 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52498/jn/bc [junit4] 2> 92135 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 92135 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=tmp_collection [junit4] 2> 92135 T452 n:127.0.0.1:52498_jn%2Fbc c:tmp_collection s:shard1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 92140 T453 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 92140 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52498/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52498_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 92142 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52498/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52498_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 92337 T392 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores\collection1 [junit4] 2> 92340 T392 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 92346 T392 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@398f3b43{/jn/bc,null,AVAILABLE} [junit4] 2> 92347 T392 oejs.AbstractConnector.doStart Started ServerConnector@322d007f{HTTP/1.1}{127.0.0.1:52507} [junit4] 2> 92347 T392 oejs.Server.doStart Started @99248ms [junit4] 2> 92347 T392 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {shards=shard3, hostContext=/jn/bc, hostPort=52507, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores} [junit4] 2> 92348 T392 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 92348 T392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\' [junit4] 2> 92370 T392 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\solr.xml [junit4] 2> 92377 T392 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores [junit4] 2> 92378 T392 oasc.CoreContainer.<init> New CoreContainer 943447702 [junit4] 2> 92378 T392 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\] [junit4] 2> 92378 T392 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\lib [junit4] 2> 92379 T392 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\lib). [junit4] 2> 92389 T392 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 92391 T392 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 92392 T392 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 92392 T392 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 92392 T392 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 92392 T392 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52482/solr [junit4] 2> 92393 T392 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 93360 T392 n:127.0.0.1:52507_jn%2Fbc oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52507_jn%2Fbc [junit4] 2> 93366 T392 n:127.0.0.1:52507_jn%2Fbc oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 93370 T392 n:127.0.0.1:52507_jn%2Fbc oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 93370 T392 n:127.0.0.1:52507_jn%2Fbc oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 93374 T392 n:127.0.0.1:52507_jn%2Fbc oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores [junit4] 2> 93376 T392 n:127.0.0.1:52507_jn%2Fbc oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores\collection1, collection=tmp_collection, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 93377 T392 n:127.0.0.1:52507_jn%2Fbc oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores\collection1\ [junit4] 2> 93377 T392 n:127.0.0.1:52507_jn%2Fbc oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 93381 T471 n:127.0.0.1:52507_jn%2Fbc c:tmp_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=tmp_collection [junit4] 2> 93382 T471 n:127.0.0.1:52507_jn%2Fbc c:tmp_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 93383 T471 n:127.0.0.1:52507_jn%2Fbc oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 93384 T453 n:127.0.0.1:52489_jn%2Fbc oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 93386 T421 n:127.0.0.1:52489_jn%2Fbc oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52507/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52507_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 93387 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52507/jn/bc", [junit4] 2> "node_name":"127.0.0.1:52507_jn%2Fbc", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"tmp_collection", [junit4] 2> "operation":"state"} [junit4] 2> 93387 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 93387 T421 n:127.0.0.1:52489_jn%2Fbc oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 94327 T471 n:127.0.0.1:52507_jn%2Fbc oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 94327 T471 n:127.0.0.1:52507_jn%2Fbc oasc.ZkController.createCollectionZkNode Check for collection zkNode:tmp_collection [junit4] 2> 94328 T471 n:127.0.0.1:52507_jn%2Fbc oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 94329 T471 n:127.0.0.1:52507_jn%2Fbc oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrRequestHandlerTest 46273C4DCB58A707-001\jetty-003\cores\collection1\' [junit4] 2> 94345 T471 n:127.0.0.1:52507_jn%2Fbc oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 94352 T471 n:127.0.0.1:52507_jn%2Fbc oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 94355 T471 n:127.0.0.1:52507_jn%2Fbc oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 94361 T471 n:127.0.0.1:52507_jn%2Fbc oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 94362 T471 n:127.0.0.1:52507_jn%2Fbc oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 94366 T471 n:127.0.0.1:52507_jn%2Fbc oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 94435 T471 n:127.0.0.1:52507_jn%2Fbc oass.IndexSchema.readSchema default search field in schema is t [...truncated too long message...] solr-core\test\J0\temp\solr.cloud.CdcrReplicationHandlerTest 46273C4DCB58A707-001\jetty-002\cores\source_collection_shard1_replica2\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrReplicationHandlerTest 46273C4DCB58A707-001\jetty-002\cores\source_collection_shard1_replica2\data\index;done=false>>] [junit4] 2> 1504763 T6684 c:source_collection s:shard1 x:source_collection_shard1_replica2 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrReplicationHandlerTest 46273C4DCB58A707-001\jetty-002\cores\source_collection_shard1_replica2\data\index [junit4] 2> 1504763 T6684 c:source_collection s:shard1 x:source_collection_shard1_replica2 oasc.Overseer.close Overseer (id=93871036725198890-127.0.0.1:55142_ny_m-n_0000000003) closing [junit4] 2> 1504763 T6972 n:127.0.0.1:55142_ny_m oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:55142_ny_m [junit4] 2> 1504766 T6968 n:127.0.0.1:55142_ny_m oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 1504767 T6684 oejs.AbstractConnector.doStop Stopped ServerConnector@7f2e5908{HTTP/1.1}{127.0.0.1:55142} [junit4] 2> 1504768 T6684 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@124b4890{/ny_m,null,UNAVAILABLE} [junit4] 2> 1504768 T6684 c:source_collection s:shard1 x:source_collection_shard1_replica2 oas.SolrTestCaseJ4.tearDown ###Ending doTest [junit4] 2> 1504768 T6684 c:source_collection s:shard1 x:source_collection_shard1_replica2 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:55126 55126 [junit4] 2> 1506715 T6685 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:55126 55126 [junit4] 2> 1506716 T6685 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 37 /solr/aliases.json [junit4] 2> 37 /solr/clusterstate.json [junit4] 2> 8 /solr/configs/conf1 [junit4] 2> 5 /solr/collections/source_collection/state.json [junit4] 2> 4 /solr/collections/source_collection/leaders/shard1 [junit4] 2> 2 /solr/collections/tmp_collection/leaders/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/collections/source_collection/cdcr/state/buffer [junit4] 2> 6 /solr/collections/source_collection/cdcr/state/process [junit4] 2> 2 /solr/collections/source_collection/leader_elect/shard1/election/93871036725198853-core_node2-n_0000000000 [junit4] 2> 2 /solr/collections/tmp_collection/cdcr/state/buffer [junit4] 2> 2 /solr/collections/source_collection/leader_elect/shard1/election/93871036725198871-core_node1-n_0000000000 [junit4] 2> 2 /solr/collections/tmp_collection/cdcr/state/process [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 35 /solr/live_nodes [junit4] 2> 4 /solr/overseer/queue [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CdcrReplicationHandlerTest -Dtests.method=doTest -Dtests.seed=46273C4DCB58A707 -Dtests.slow=true -Dtests.locale=tr -Dtests.timezone=Africa/Libreville -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] FAILURE 375s J0 | CdcrReplicationHandlerTest.doTest <<< [junit4] > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 330 seconds [junit4] > at __randomizedtesting.SeedInfo.seed([46273C4DCB58A707:E16384E9A6E3B4BE]:0) [junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:172) [junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133) [junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:128) [junit4] > at org.apache.solr.cloud.BaseCdcrDistributedZkTest.waitForRecoveriesToFinish(BaseCdcrDistributedZkTest.java:419) [junit4] > at org.apache.solr.cloud.BaseCdcrDistributedZkTest.restartServer(BaseCdcrDistributedZkTest.java:460) [junit4] > at org.apache.solr.cloud.CdcrReplicationHandlerTest.doTestPartialReplication(CdcrReplicationHandlerTest.java:109) [junit4] > at org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest(CdcrReplicationHandlerTest.java:51) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1506721 T6684 c:source_collection s:shard1 x:source_collection_shard1_replica2 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrReplicationHandlerTest 46273C4DCB58A707-001 [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=tr, timezone=Africa/Libreville [junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=3,threads=1,free=246523832,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [SliceStateTest, UniqFieldsUpdateProcessorFactoryTest, TestDistributedSearch, TestPostingsSolrHighlighter, CollectionReloadTest, SolrTestCaseJ4Test, JSONWriterTest, TestRestManager, SimpleFacetsTest, PathHierarchyTokenizerFactoryTest, ConcurrentDeleteAndCreateCollectionTest, SOLR749Test, TestStressReorder, TestSchemaVersionResource, TestFileDictionaryLookup, TestCollationField, TestIBSimilarityFactory, SampleTest, TestDocumentBuilder, TestMiniSolrCloudClusterSSL, TestCryptoKeys, TestOrdValues, TestManagedSchemaFieldResource, TestXIncludeConfig, RuleEngineTest, SharedFSAutoReplicaFailoverTest, TestShardHandlerFactory, TestDynamicLoading, TestComponentsName, LeaderFailoverAfterPartitionTest, TestSchemaResource, TestCursorMarkWithoutUniqueKey, SoftAutoCommitTest, PeerSyncTest, DistributedQueryElevationComponentTest, TestDefaultStatsCache, OverseerTest, TestLeaderElectionZkExpiry, TestConfigSets, TestPivotHelperCode, LeaderElectionTest, TestUpdate, UUIDFieldTest, DistanceUnitsTest, InfoHandlerTest, CircularListTest, PrimUtilsTest, SynonymTokenizerTest, DeleteInactiveReplicaTest, TestReplicationHandler, TestStressLucene, TestDocBasedVersionConstraints, CollectionsAPIAsyncDistributedZkTest, HdfsNNFailoverTest, TestCoreContainer, TestFieldResource, TestMacros, TestAnalyzedSuggestions, ConnectionManagerTest, CoreAdminRequestStatusTest, DOMUtilTest, TestTrackingShardHandlerFactory, TestStressVersions, RemoteQueryErrorTest, DistribDocExpirationUpdateProcessorTest, TestFastLRUCache, TestMaxScoreQueryParser, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestLRUCache, SolrIndexSplitterTest, SyncSliceTest, DeleteReplicaTest, HighlighterMaxOffsetTest, HdfsRecoveryZkTest, TestSweetSpotSimilarityFactory, XmlUpdateRequestHandlerTest, TestGroupingSearch, QueryResultKeyTest, ClusterStateTest, SSLMigrationTest, TestCollationFieldDocValues, TestCloudPivotFacet, TestChildDocTransformer, BadComponentTest, NoCacheHeaderTest, TestComplexPhraseQParserPlugin, StressHdfsTest, TestSolrXml, DistribCursorPagingTest, PreAnalyzedFieldTest, TestFreeTextSuggestions, AlternateDirectoryTest, TestIndexingPerformance, TestStressRecovery, HdfsSyncSliceTest, ClusterStateUpdateTest, DocValuesTest, TestIndexSearcher, TestArbitraryIndexDir, TestJmxMonitoredMap, TestJsonFacets, TimeZoneUtilsTest, TestDistributedMissingSort, TestRawResponseWriter, TestSolrQueryParserDefaultOperatorResource, IndexSchemaRuntimeFieldTest, SolrCoreTest, ResponseLogComponentTest, FacetPivotSmallTest, TestFaceting, DistributedIntervalFacetingTest, SolrCoreCheckLockOnStartupTest, DistributedFacetPivotWhiteBoxTest, TestHashQParserPlugin, CdcrReplicationHandlerTest] [junit4] Completed [495/502] on J0 in 375.44s, 1 test, 1 failure <<< FAILURES! [...truncated 30 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:526: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:474: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:229: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:512: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1415: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:973: There were test failures: 502 suites, 1986 tests, 3 suite-level errors, 2 failures, 1475 ignored (20 assumptions) Total time: 45 minutes 12 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts 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