Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/5120/ Java: 64bit/jdk1.8.0_51 -XX:+UseCompressedOops -XX:+UseParallelGC
4 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores Error Message: ERROR: SolrIndexSearcher opens=51 closes=50 Stack Trace: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50 at __randomizedtesting.SeedInfo.seed([AD0E42FB829A4158]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:467) at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:233) at sun.reflect.GeneratedMethodAccessor40.invoke(Unknown Source) 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.core.TestLazyCores Error Message: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([AD0E42FB829A4158]:0) FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([AD0E42FB829A4158]:0) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: [index.20150807195704724, index.20150807195705207, index.properties, replication.properties] expected:<1> but was:<2> Stack Trace: java.lang.AssertionError: [index.20150807195704724, index.20150807195705207, index.properties, replication.properties] expected:<1> but was:<2> at __randomizedtesting.SeedInfo.seed([AD0E42FB829A4158:76A5423D87B228EB]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:818) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:785) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java: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 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 10542 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\init-core-data-001 [junit4] 2> 1558529 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestDetails [junit4] 2> 1558530 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\collection1 [junit4] 2> 1558536 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1558537 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4498546{/solr,null,AVAILABLE} [junit4] 2> 1558539 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2cc0775f{HTTP/1.1}{127.0.0.1:49650} [junit4] 2> 1558539 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.Server Started @1567616ms [junit4] 2> 1558539 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=49650} [junit4] 2> 1558539 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1558539 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\' [junit4] 2> 1558553 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\solr.xml [junit4] 2> 1558558 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\. [junit4] 2> 1558558 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer New CoreContainer 125914309 [junit4] 2> 1558558 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\] [junit4] 2> 1558558 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\lib [junit4] 2> 1558558 WARN (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrResourceLoader 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.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\lib). [junit4] 2> 1558562 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1558564 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1558564 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1558564 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1558564 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1558564 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1558566 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\. [junit4] 2> 1558567 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\, dataDir=data\} [junit4] 2> 1558567 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\ [junit4] 2> 1558567 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1558568 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\' [junit4] 2> 1558568 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1 [junit4] 2> 1558569 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1558582 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1558583 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1558586 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1558586 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\conf\schema.xml [junit4] 2> 1558588 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1558590 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\conf' [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\ [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\], dataDir=[null] [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\data\ [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\data\index/ [junit4] 2> 1558592 WARN (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\data\index [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=2.8984375, floorSegmentMB=1.0693359375, forceMergeDeletesPctAllowed=2.2068262134822536, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5836746547787532 [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_1,generation=1} [junit4] 2> 1558592 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1558593 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\data\ [junit4] 2> 1558594 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1558596 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1558597 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1558597 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1558598 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1558604 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1558604 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1558606 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1558606 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1558606 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1558606 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=32.904296875, floorSegmentMB=1.9521484375, forceMergeDeletesPctAllowed=24.50665688429966, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1558606 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_1,generation=1} [junit4] 2> 1558606 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1558606 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7b7eb8b[collection1] main [junit4] 2> 1558607 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\conf [junit4] 2> 1558607 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\conf} [junit4] 2> 1558607 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-001\.\collection1\conf [junit4] 2> 1558607 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1558607 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 1558607 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1558607 INFO (searcherExecutor-4739-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b7eb8b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1558607 INFO (coreLoadExecutor-4738-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1558960 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\collection1 [junit4] 2> 1558966 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1558967 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ab86b97{/solr,null,AVAILABLE} [junit4] 2> 1558968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.ServerConnector Started ServerConnector@725dc432{HTTP/1.1}{127.0.0.1:49653} [junit4] 2> 1558968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.Server Started @1568171ms [junit4] 2> 1558968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=49653} [junit4] 2> 1558969 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1558969 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\' [junit4] 2> 1558988 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\solr.xml [junit4] 2> 1558991 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\. [junit4] 2> 1558991 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer New CoreContainer 1802955277 [junit4] 2> 1558991 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\] [junit4] 2> 1558991 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\lib [junit4] 2> 1558991 WARN (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrResourceLoader 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.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\lib). [junit4] 2> 1558997 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1558999 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1558999 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1559000 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1559000 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1559000 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1559000 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\. [junit4] 2> 1559001 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\, dataDir=data\} [junit4] 2> 1559001 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\ [junit4] 2> 1559002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1559003 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\' [junit4] 2> 1559003 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1 [junit4] 2> 1559003 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1559016 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1559018 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1559020 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1559020 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\conf\schema.xml [junit4] 2> 1559022 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1559024 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1559025 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\conf' [junit4] 2> 1559025 INFO (coreLoadExecutor-4748-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\ [junit4] 2> 1559025 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1559025 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\], dataDir=[null] [junit4] 2> 1559025 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 1559026 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\ [junit4] 2> 1559026 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index/ [junit4] 2> 1559026 WARN (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 1559026 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index [junit4] 2> 1559026 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=2.8984375, floorSegmentMB=1.0693359375, forceMergeDeletesPctAllowed=2.2068262134822536, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5836746547787532 [junit4] 2> 1559026 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_1,generation=1} [junit4] 2> 1559026 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1559027 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\ [junit4] 2> 1559028 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1559030 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559031 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559031 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559032 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559033 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1559033 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1559033 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=32.904296875, floorSegmentMB=1.9521484375, forceMergeDeletesPctAllowed=24.50665688429966, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_1,generation=1} [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@341fef99[collection1] main [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\conf [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\conf} [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\conf [junit4] 2> 1559034 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1559036 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll Scheduled at an interval of 1000ms [junit4] 2> 1559037 INFO (coreLoadExecutor-4748-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1559037 INFO (searcherExecutor-4749-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@341fef99[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1559392 INFO (qtp1853950299-11080) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 1559393 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1559395 INFO (qtp1853950299-11082) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1559396 INFO (qtp225900203-11098) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1 [junit4] 2> 1559397 INFO (qtp1853950299-11083) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0 [junit4] 2> 1559399 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1559400 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_2,generation=2} [junit4] 2> 1559400 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2 [junit4] 2> 1559401 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@73d71b56[collection1] main [junit4] 2> 1559401 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 1559401 INFO (searcherExecutor-4739-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@73d71b56[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1)))} [junit4] 2> 1559401 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 2 [junit4] 2> 1559403 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1559405 INFO (qtp1853950299-11085) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1559405 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 1559405 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1559405 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1559411 INFO (qtp1853950299-11080) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=4 [junit4] 2> 1559411 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 1559411 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641845 [junit4] 2> 1559411 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter... [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_1,generation=1} [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_1,generation=1} [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing IndexWriter... [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 1559412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download to MockDirectoryWrapper(RAMDirectory@629fb2a6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6bd06e84) fullCopy=false [junit4] 2> 1559414 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559416 INFO (qtp1853950299-11082) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559418 INFO (qtp1853950299-11083) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559420 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559420 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download : 0 secs [junit4] 2> 1559421 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter... [junit4] 2> 1559421 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=4.521484375, floorSegmentMB=1.6416015625, forceMergeDeletesPctAllowed=23.542286369249098, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1559423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_2,generation=2} [junit4] 2> 1559423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2 [junit4] 2> 1559423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1559423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6054da4e[collection1] main [junit4] 2> 1559423 INFO (searcherExecutor-4749-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6054da4e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1)))} [junit4] 2> 1559424 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641845 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641845;done=true>>] [junit4] 2> 1559424 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641845 [junit4] 2> 1559424 INFO (qtp225900203-11099) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49650/solr/collection1/replication&command=fetchindex} status=0 QTime=26 [junit4] 2> 1559431 INFO (qtp1853950299-11085) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1559431 INFO (qtp225900203-11100) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 1559434 INFO (qtp1853950299-11080) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1 [junit4] 2> 1559436 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1559437 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_3,generation=3} [junit4] 2> 1559437 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 3 [junit4] 2> 1559437 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3b848a17[collection1] main [junit4] 2> 1559437 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 1559437 INFO (searcherExecutor-4739-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b848a17[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1) Uninverting(_1(6.0.0):c1)))} [junit4] 2> 1559437 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 2 [junit4] 2> 1559439 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1559440 INFO (qtp1853950299-11082) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1559440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 1559440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 1559440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1559442 INFO (qtp1853950299-11083) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 1559442 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7 [junit4] 2> 1559442 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641876 [junit4] 2> 1559443 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter... [junit4] 2> 1559443 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 1559443 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 1559443 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1559443 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_2,generation=2} [junit4] 2> 1559443 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2 [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_2,generation=2} [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2 [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing IndexWriter... [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download to MockDirectoryWrapper(RAMDirectory@5cbd9d12 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2070ca8a) fullCopy=false [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 1559444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 1559446 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559448 INFO (qtp1853950299-11085) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559449 INFO (qtp1853950299-11080) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559451 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559451 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download : 0 secs [junit4] 2> 1559452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter... [junit4] 2> 1559453 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=4.521484375, floorSegmentMB=1.6416015625, forceMergeDeletesPctAllowed=23.542286369249098, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1559453 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_3,generation=3} [junit4] 2> 1559453 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 3 [junit4] 2> 1559453 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1559454 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@207c6b14[collection1] main [junit4] 2> 1559454 INFO (searcherExecutor-4749-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@207c6b14[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1) Uninverting(_1(6.0.0):c1)))} [junit4] 2> 1559454 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641876 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641876;done=true>>] [junit4] 2> 1559454 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641876 [junit4] 2> 1559455 INFO (qtp225900203-11101) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49650/solr/collection1/replication&command=fetchindex} status=0 QTime=20 [junit4] 2> 1559458 INFO (qtp1853950299-11082) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1559458 INFO (qtp225900203-11102) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 1559460 INFO (qtp1853950299-11083) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0 [junit4] 2> 1559462 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1559463 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=3 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5af669bd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60091d3d),segFN=segments_4,generation=4} [junit4] 2> 1559463 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 4 [junit4] 2> 1559463 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@63ba96dd[collection1] main [junit4] 2> 1559463 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 1559463 INFO (searcherExecutor-4739-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@63ba96dd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1) Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1)))} [junit4] 2> 1559464 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 1 [junit4] 2> 1559464 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1559466 INFO (qtp1853950299-11085) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1559466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 1559466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 1559466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1559467 INFO (qtp1853950299-11080) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 1559468 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 1559468 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641902 [junit4] 2> 1559468 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter... [junit4] 2> 1559468 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 1559468 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_3,generation=3} [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 3 [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_3,generation=3} [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 3 [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing IndexWriter... [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 1559469 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 1559470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download to MockDirectoryWrapper(RAMDirectory@4fa15978 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d09f1c1) fullCopy=false [junit4] 2> 1559470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 1559470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 1559470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 1559470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists [junit4] 2> 1559470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists [junit4] 2> 1559470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists [junit4] 2> 1559471 INFO (qtp1853950299-11081) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559473 INFO (qtp1853950299-11082) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559475 INFO (qtp1853950299-11083) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559476 INFO (qtp1853950299-11084) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1559476 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download : 0 secs [junit4] 2> 1559478 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter... [junit4] 2> 1559478 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=4.521484375, floorSegmentMB=1.6416015625, forceMergeDeletesPctAllowed=23.542286369249098, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1559479 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c753c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f6a7f26),segFN=segments_4,generation=4} [junit4] 2> 1559479 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 4 [junit4] 2> 1559479 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1559479 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@c51a7c0[collection1] main [junit4] 2> 1559480 INFO (searcherExecutor-4749-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c51a7c0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c1) Uninverting(_1(6.0.0):c1) Uninverting(_2(6.0.0):c1)))} [junit4] 2> 1559480 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641902 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641902;done=true>>] [junit4] 2> 1559480 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-002\.\collection1\data\index.20150807195641902 [junit4] 2> 1559480 INFO (qtp225900203-11103) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49650/solr/collection1/replication&command=fetchindex} status=0 QTime=20 [junit4] 2> 1559481 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\collection1 [junit4] 2> 1559488 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1559489 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@235a076a{/solr,null,AVAILABLE} [junit4] 2> 1559489 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.ServerConnector Started ServerConnector@17d0cf1d{HTTP/1.1}{127.0.0.1:49661} [junit4] 2> 1559489 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.e.j.s.Server Started @1568844ms [junit4] 2> 1559489 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\collection1\data, hostContext=/solr, hostPort=49661} [junit4] 2> 1559489 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1559489 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\' [junit4] 2> 1559503 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\solr.xml [junit4] 2> 1559507 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\. [junit4] 2> 1559507 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer New CoreContainer 136529795 [junit4] 2> 1559507 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\] [junit4] 2> 1559507 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\lib [junit4] 2> 1559507 WARN (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrResourceLoader 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.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\lib). [junit4] 2> 1559512 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1559514 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1559514 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1559514 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1559514 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1559514 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1559515 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\. [junit4] 2> 1559516 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\, dataDir=data\} [junit4] 2> 1559517 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\ [junit4] 2> 1559517 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1559517 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\' [junit4] 2> 1559517 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1 [junit4] 2> 1559518 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[AD0E42FB829A4158]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1559532 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1559533 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1559536 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1559537 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\conf\schema.xml [junit4] 2> 1559538 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1559540 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1559541 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\conf' [junit4] 2> 1559542 INFO (coreLoadExecutor-4762-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\ [junit4] 2> 1559542 INFO (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1559542 INFO (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\], dataDir=[null] [junit4] 2> 1559542 INFO (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 1559542 INFO (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\data\ [junit4] 2> 1559542 INFO (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\data\index/ [junit4] 2> 1559542 WARN (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 1559543 INFO (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_AD0E42FB829A4158-001\solr-instance-003\.\collection1\data\index [junit4] 2> 1559543 INFO (coreLoadExecutor-4762-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wra [...truncated too long message...] nit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Aug 07, 2015 11:02:29 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] [junit4] 2> Aug 07, 2015 11:02:32 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated: [junit4] 2> 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {v_t=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneVarGapFixedInterval), v_s=FST50, v_f=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=it_IT, timezone=Europe/Bucharest [junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_51 (64-bit)/cpus=3,threads=2,free=118241784,total=492306432 [junit4] 2> NOTE: All tests run in this JVM: [TermVectorComponentDistributedTest, CustomCollectionTest, DistributedMLTComponentTest, SchemaVersionSpecificBehaviorTest, TestManagedResourceStorage, DateMathParserTest, OpenExchangeRatesOrgProviderTest, HdfsBasicDistributedZkTest, RAMDirectoryFactoryTest, DebugComponentTest, HdfsSyncSliceTest, SpellingQueryConverterTest, TestObjectReleaseTracker, TestIntervalFaceting, ExitableDirectoryReaderTest, TermsComponentTest, CurrencyFieldOpenExchangeTest, DataDrivenBlockJoinTest, HLLSerializationTest, TestSchemaSimilarityResource, TestCopyFieldCollectionResource, TestRTimer, PluginInfoTest, TestPseudoReturnFields, FileUtilsTest, SearchHandlerTest, CdcrReplicationDistributedZkTest, TestJoin, TestPivotHelperCode, TestRandomMergePolicy, TestDefaultStatsCache, TestSchemaNameResource, TestSolrQueryParserDefaultOperatorResource, TestDistribIDF, DistribDocExpirationUpdateProcessorTest, TestClusterStateMutator, TestReqParamsAPI, TestRangeQuery, ZkSolrClientTest, TestInitParams, TestJettySolrRunner, ShardRoutingCustomTest, MoreLikeThisHandlerTest, TestCursorMarkWithoutUniqueKey, TestCloudPivotFacet, TestDFRSimilarityFactory, XsltUpdateRequestHandlerTest, BlockCacheTest, TestExpandComponent, TestFieldTypeCollectionResource, TestNoOpRegenerator, RegexBoostProcessorTest, BadCopyFieldTest, LoggingHandlerTest, TestCloudInspectUtil, TestUtils, TestCloudSchemaless, ExplicitHLLTest, RecoveryAfterSoftCommitTest, CopyFieldTest, RuleEngineTest, TestRawResponseWriter, TestHashQParserPlugin, HLLUtilTest, SolrIndexConfigTest, TestSolrQueryParser, TestSolrXml, TestBulkSchemaAPI, TestRemoveLastDynamicCopyField, DistributedFacetPivotSmallTest, HdfsNNFailoverTest, TermVectorComponentTest, CoreAdminRequestStatusTest, LeaderElectionIntegrationTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, ConnectionManagerTest, HdfsWriteToMultipleCollectionsTest, TestRealTimeGet, TestSolrJ, AssignTest, TestConfigSetImmutable, LeaderInitiatedRecoveryOnCommitTest, TestBadConfig, TestCollapseQParserPlugin, StatelessScriptUpdateProcessorFactoryTest, QueryEqualityTest, PolyFieldTest, IndexSchemaTest, SpatialFilterTest, TestRandomRequestDistribution, TestValueSourceCache, DirectSolrSpellCheckerTest, HdfsDirectoryFactoryTest, TestCryptoKeys, RulesTest, MBeansHandlerTest, SimplePostToolTest, TestQuerySenderListener, CdcrVersionReplicationTest, MergeStrategyTest, TestNamedUpdateProcessors, TestFoldingMultitermQuery, OpenCloseCoreStressTest, TestAuthenticationFramework, HighlighterConfigTest, FullSolrCloudDistribCmdsTest, TestManagedSchemaDynamicFieldResource, TestConfig, TestRecovery, HdfsRecoveryZkTest, ResponseHeaderTest, TestFileDictionaryLookup, TestRebalanceLeaders, AsyncMigrateRouteKeyTest, CursorMarkTest, RemoteQueryErrorTest, EchoParamsTest, TestZkChroot, TestSolrDeletionPolicy2, TestDefaultSearchFieldResource, QueryResultKeyTest, TestImplicitCoreProperties, CachingDirectoryFactoryTest, TestSortingResponseWriter, TestShortCircuitedRequests, BasicZkTest, TestDynamicFieldResource, LeaderFailoverAfterPartitionTest, TestSolrConfigHandlerCloud, SyncSliceTest, TestReplicationHandler, AutoCommitTest, TestDistributedGrouping, TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, TestReload, DistributedTermsComponentTest, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, SpellCheckComponentTest, SolrCmdDistributorTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, BasicFunctionalityTest, TestLazyCores] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores -Dtests.seed=AD0E42FB829A4158 -Dtests.slow=true -Dtests.locale=it_IT -Dtests.timezone=Europe/Bucharest -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] ERROR 0.00s J1 | TestLazyCores (suite) <<< [junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=51 closes=50 [junit4] > at __randomizedtesting.SeedInfo.seed([AD0E42FB829A4158]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:467) [junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:233) [junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: [junit4] > 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > at __randomizedtesting.SeedInfo.seed([AD0E42FB829A4158]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=13202, name=searcherExecutor-6088-thread-1, state=WAITING, group=TGRP-TestLazyCores] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > at __randomizedtesting.SeedInfo.seed([AD0E42FB829A4158]:0) [junit4] Completed [393/539] on J1 in 151.27s, 7 tests, 1 failure, 2 errors <<< FAILURES! [...truncated 453 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:716: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:660: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:59: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:230: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:514: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1429: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:984: There were test failures: 539 suites, 2126 tests, 3 suite-level errors, 1 failure, 502 ignored (40 assumptions) Total time: 50 minutes 18 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