lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_112) - Build # 667 - Unstable!
Date Wed, 04 Jan 2017 14:19:04 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/667/
Java: 32bit/jdk1.8.0_112 -server -XX:+UseG1GC

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.lucene.store.TestRAFDirectory

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001\testThreadSafety-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001\testThreadSafety-001    C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001\testThreadSafety-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001\testThreadSafety-001
   C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001

	at __randomizedtesting.SeedInfo.seed([91D47FD6249821DC]:0)
	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
	at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
	at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.handler.TestReplicationHandler.doTestStressReplication

Error Message:
[index.20170104151538048, index.20170104151538691, index.properties, replication.properties, snapshot_metadata] expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: [index.20170104151538048, index.20170104151538691, index.properties, replication.properties, snapshot_metadata] expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([5125C27F85508D6D:8A8EC2B98078E4DE]: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:925)
	at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:883)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([5125C27F85508D6D:9DF5FFC345DE7656]:0)
	at org.junit.Assert.fail(Assert.java:92)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertTrue(Assert.java:54)
	at org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics(SolrIndexMetricsTest.java:66)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 7656 lines...]
   [junit4] Suite: org.apache.lucene.store.TestRAFDirectory
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=223, maxMBSortInHeap=5.080794501320147, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=pt, timezone=Pacific/Nauru
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_112 (32-bit)/cpus=3,threads=1,free=19744224,total=67108864
   [junit4]   2> NOTE: All tests run in this JVM: [TestFieldCacheWithThreads, TestSlowCompositeReaderWrapper, TestFieldCacheSanityChecker, TestIndexSplitter, TestNumericTerms32, TestRAFDirectory]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRAFDirectory -Dtests.seed=91D47FD6249821DC -Dtests.slow=true -Dtests.locale=pt -Dtests.timezone=Pacific/Nauru -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestRAFDirectory (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001\testThreadSafety-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001\testThreadSafety-001
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_91D47FD6249821DC-001
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([91D47FD6249821DC]:0)
   [junit4]    > 	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [10/24 (1!)] on J1 in 4.47s, 44 tests, 1 error <<< FAILURES!

[...truncated 3262 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\init-core-data-001
   [junit4]   2> 249563 INFO  (SUITE-TestReplicationHandler-seed#[5125C27F85508D6D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 249565 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
   [junit4]   2> 249566 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\collection1
   [junit4]   2> 249581 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 249583 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b3ebb{/solr,null,AVAILABLE}
   [junit4]   2> 249584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@76c019{HTTP/1.1,[http/1.1]}{127.0.0.1:53053}
   [junit4]   2> 249584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server Started @257667ms
   [junit4]   2> 249584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=53053}
   [junit4]   2> 249584 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 249584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.4.0
   [junit4]   2> 249584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 249584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 249584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-04T13:15:06.212Z
   [junit4]   2> 249585 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\solr.xml
   [junit4]   2> 249601 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 249611 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\.
   [junit4]   2> 249611 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 249634 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 249646 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 249656 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 249670 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\.\collection1
   [junit4]   2> 249671 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\.\collection1\data\]
   [junit4]   2> 249673 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2007862301, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 249695 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 249695 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 249695 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=65.259765625, floorSegmentMB=1.0771484375, forceMergeDeletesPctAllowed=15.272702382954153, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 249697 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a39b48[collection1] main]
   [junit4]   2> 249698 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-001\collection1\conf
   [junit4]   2> 249699 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 249699 INFO  (coreLoadExecutor-769-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 249700 INFO  (searcherExecutor-770-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a39b48[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 250182 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\collection1
   [junit4]   2> 250192 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 250192 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e9b3ea{/solr,null,AVAILABLE}
   [junit4]   2> 250193 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1ad978e{HTTP/1.1,[http/1.1]}{127.0.0.1:53067}
   [junit4]   2> 250194 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server Started @258277ms
   [junit4]   2> 250194 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=53067}
   [junit4]   2> 250194 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 250194 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.4.0
   [junit4]   2> 250194 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 250194 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 250194 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-04T13:15:06.822Z
   [junit4]   2> 250195 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\solr.xml
   [junit4]   2> 250201 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 250209 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\.
   [junit4]   2> 250209 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 250223 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 250232 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 250279 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 250281 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\.\collection1
   [junit4]   2> 250282 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\.\collection1\data\]
   [junit4]   2> 250284 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2007862301, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 250295 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 250295 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 250296 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=65.259765625, floorSegmentMB=1.0771484375, forceMergeDeletesPctAllowed=15.272702382954153, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 250296 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@18e0fee[collection1] main]
   [junit4]   2> 250296 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-002\collection1\conf
   [junit4]   2> 250298 INFO  (coreLoadExecutor-779-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
   [junit4]   2> 250299 INFO  (searcherExecutor-780-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18e0fee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 250313 INFO  (qtp1087741-1585) [    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> 250316 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 1
   [junit4]   2> 250318 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 250318 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c3eab
   [junit4]   2> 250321 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@141be65[collection1] main]
   [junit4]   2> 250321 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 250321 INFO  (searcherExecutor-770-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@141be65[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C1)))}
   [junit4]   2> 250322 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 3
   [junit4]   2> 250346 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 250348 INFO  (qtp1087741-1580) [    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> 250349 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 250349 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1483535706946
   [junit4]   2> 250349 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 250349 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 250358 INFO  (qtp1087741-1580) [    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=8
   [junit4]   2> 250358 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 250362 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1428839749, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 250363 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 250363 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@159a82a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@74da95))
   [junit4]   2> 250366 INFO  (qtp1087741-1578) [    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> 250371 INFO  (qtp1087741-1577) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250375 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250377 INFO  (qtp1087741-1575) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250380 INFO  (qtp1087741-1585) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250387 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250391 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250393 INFO  (qtp1087741-1579) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250395 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250397 INFO  (qtp1087741-1578) [    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> 250397 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1612) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@159a82a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@74da95))
   [junit4]   2> 250402 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4379479395292203]
   [junit4]   2> 250404 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 250404 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@90d628[collection1] main]
   [junit4]   2> 250404 INFO  (searcherExecutor-780-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@90d628[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C1)))}
   [junit4]   2> 250404 INFO  (qtp32465607-1597) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:53053/solr/collection1/replication&command=fetchindex} status=0 QTime=60
   [junit4]   2> 250413 INFO  (qtp32465607-1599) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 250415 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 250416 INFO  (qtp1087741-1577) [    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> 250420 INFO  (qtp1087741-1587) [    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> 250426 INFO  (qtp1087741-1575) [    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> 250426 INFO  (qtp32465607-1600) [    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=4
   [junit4]   2> 250428 INFO  (qtp1087741-1585) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
   [junit4]   2> 250430 INFO  (qtp32465607-1601) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
   [junit4]   2> 250431 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 250431 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 250432 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 250432 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 250434 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 250437 INFO  (qtp1087741-1581) [    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> 250437 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 250437 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1483535706946
   [junit4]   2> 250437 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 250437 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 250439 INFO  (qtp32465607-1602) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:53053/solr/collection1/replication&command=fetchindex} status=0 QTime=4
   [junit4]   2> 250442 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 1
   [junit4]   2> 250443 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 250443 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c3eab
   [junit4]   2> 250445 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12e46c3[collection1] main]
   [junit4]   2> 250446 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 250446 INFO  (searcherExecutor-770-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12e46c3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C1) Uninverting(_1(6.4.0):C1)))}
   [junit4]   2> 250446 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 3
   [junit4]   2> 250447 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 250450 INFO  (qtp1087741-1578) [    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> 250450 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 250450 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1483535707071
   [junit4]   2> 250450 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 250450 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 250455 INFO  (qtp1087741-1578) [    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=2
   [junit4]   2> 250455 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 19
   [junit4]   2> 250458 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1428839749, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 250459 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 250461 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b89100 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8aa3b))
   [junit4]   2> 250461 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 250461 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.doc because it already exists
   [junit4]   2> 250462 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tim because it already exists
   [junit4]   2> 250462 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 250462 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 250462 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tip because it already exists
   [junit4]   2> 250462 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 250462 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 250462 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 250464 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250469 INFO  (qtp1087741-1575) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250473 INFO  (qtp1087741-1585) [    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> 250476 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250478 INFO  (qtp1087741-1579) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250497 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250500 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250502 INFO  (qtp1087741-1577) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250504 INFO  (qtp1087741-1577) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 250507 INFO  (qtp1087741-1587) [    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> 250509 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1674) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b89100 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8aa3b))
   [junit4]   2> 250521 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4379479395292203]
   [junit4]   2> 250525 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 250529 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@56710f[collection1] main]
   [junit4]   2> 250530 INFO  (searcherExecutor-780-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@56710f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C1) Uninverting(_1(6.4.0):C1)))}
   [junit4]   2> 250530 INFO  (qtp32465607-1595) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:53053/solr/collection1/replication&command=fetchindex} status=0 QTime=82
   [junit4]   2> 250532 INFO  (qtp32465607-1597) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 250533 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 250612 INFO  (qtp1087741-1575) [    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=77
   [junit4]   2> 250617 INFO  (qtp1087741-1585) [    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> 250625 INFO  (qtp1087741-1582) [    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=1
   [junit4]   2> 250626 INFO  (qtp32465607-1597) [    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=5
   [junit4]   2> 250628 INFO  (qtp1087741-1579) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
   [junit4]   2> 250629 INFO  (qtp32465607-1599) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
   [junit4]   2> 250631 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 250634 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 250634 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c3eab
   [junit4]   2> 250643 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4eba0a[collection1] realtime]
   [junit4]   2> 250643 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 250643 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 9
   [junit4]   2> 250646 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 250648 INFO  (qtp1087741-1578) [    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> 250649 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 4
   [junit4]   2> 250649 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1483535707262
   [junit4]   2> 250649 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 250649 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 250653 INFO  (qtp1087741-1577) [    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=2
   [junit4]   2> 250653 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 28
   [junit4]   2> 250657 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1428839749, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 250658 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 250658 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 250658 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 251659 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 251659 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 252659 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 252659 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 253660 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 253660 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 254804 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 254804 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 255816 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 255816 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 256817 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 256817 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 257817 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 257817 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 258847 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 258847 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 259896 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 259907 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 260909 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 260910 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
   [junit4]   2> 261942 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index files to be delete-able
   [junit4]   2> 261942 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1329fa3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d313cf))
   [junit4]   2> 261943 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 261943 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.doc because it already exists
   [junit4]   2> 261943 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tim because it already exists
   [junit4]   2> 261943 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tip because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.doc because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.tim because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
   [junit4]   2> 261955 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.tip because it already exists
   [junit4]   2> 261982 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 261989 INFO  (qtp1087741-1582) [    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> 261992 INFO  (qtp1087741-1579) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 261995 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262019 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262022 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262025 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262034 INFO  (qtp1087741-1578) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262181 INFO  (qtp1087741-1578) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262255 INFO  (qtp1087741-1577) [    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> 262256 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1736) : 11 secs (157 bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1329fa3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d313cf))
   [junit4]   2> 262513 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4379479395292203]
   [junit4]   2> 262529 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 262531 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ffdef9[collection1] main]
   [junit4]   2> 262531 INFO  (searcherExecutor-780-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ffdef9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C1) Uninverting(_1(6.4.0):C1) Uninverting(_2(6.4.0):C1)))}
   [junit4]   2> 262532 INFO  (qtp32465607-1599) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:53053/solr/collection1/replication&command=fetchindex} status=0 QTime=11887
   [junit4]   2> 262537 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 262538 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
   [junit4]   2> 262545 INFO  (qtp32465607-1601) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=4
   [junit4]   2> 262547 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 262549 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 262551 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 262551 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c3eab
   [junit4]   2> 262603 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f2010a[collection1] main]
   [junit4]   2> 262603 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 262603 INFO  (searcherExecutor-770-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f2010a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C1) Uninverting(_1(6.4.0):C1) Uninverting(_2(6.4.0):C1) Uninverting(_3(6.4.0):C1)))}
   [junit4]   2> 262604 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 52
   [junit4]   2> 262611 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 262616 INFO  (qtp1087741-1582) [    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> 262616 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 5
   [junit4]   2> 262616 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1483535719179
   [junit4]   2> 262616 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4
   [junit4]   2> 262616 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 262628 INFO  (qtp1087741-1579) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=9
   [junit4]   2> 262632 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 37
   [junit4]   2> 262650 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1428839749, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 262655 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 262656 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b67ba3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30c860))
   [junit4]   2> 262656 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 262660 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.doc because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tim because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tip because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.doc because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.tim because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 262661 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
   [junit4]   2> 262662 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
   [junit4]   2> 262662 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
   [junit4]   2> 262662 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
   [junit4]   2> 262664 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
   [junit4]   2> 262664 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.tip because it already exists
   [junit4]   2> 262664 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.nvd because it already exists
   [junit4]   2> 262664 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
   [junit4]   2> 262667 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.fdt because it already exists
   [junit4]   2> 262667 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.fnm because it already exists
   [junit4]   2> 262667 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.nvm because it already exists
   [junit4]   2> 262667 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.fdx because it already exists
   [junit4]   2> 262667 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2_Lucene50_0.doc because it already exists
   [junit4]   2> 262667 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2_Lucene50_0.tim because it already exists
   [junit4]   2> 262667 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2_Lucene50_0.tip because it already exists
   [junit4]   2> 262670 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262678 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262683 INFO  (qtp1087741-1578) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262685 INFO  (qtp1087741-1577) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262714 INFO  (qtp1087741-1587) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262785 INFO  (qtp1087741-1582) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262789 INFO  (qtp1087741-1579) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262856 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262866 INFO  (qtp1087741-1580) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262878 INFO  (qtp1087741-1578) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 262913 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1798) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b67ba3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30c860))
   [junit4]   2> 263029 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4379479395292203]
   [junit4]   2> 263175 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 263277 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@28fcd[collection1] main]
   [junit4]   2> 263279 INFO  (searcherExecutor-780-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@28fcd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C1) Uninverting(_1(6.4.0):C1) Uninverting(_2(6.4.0):C1) Uninverting(_3(6.4.0):C1)))}
   [junit4]   2> 263279 INFO  (qtp32465607-1601) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:53053/solr/collection1/replication&command=fetchindex} status=0 QTime=673
   [junit4]   2> 263285 INFO  (qtp1087741-1577) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=0
   [junit4]   2> 263293 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 263295 INFO  (qtp32465607-1595) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=0
   [junit4]   2> 263295 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 263461 INFO  (qtp1087741-1577) [    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=163
   [junit4]   2> 263474 INFO  (qtp1087741-1582) [    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=10
   [junit4]   2> 263563 INFO  (qtp1087741-1579) [    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=56
   [junit4]   2> 263565 INFO  (qtp32465607-1595) [    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=82
   [junit4]   2> 263571 INFO  (qtp1087741-1581) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
   [junit4]   2> 263573 INFO  (qtp32465607-1597) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
   [junit4]   2> 263573 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
   [junit4]   2> 263575 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@76c019{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 263575 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=16156507
   [junit4]   2> 263577 INFO  (coreCloseExecutor-789-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1f2a876
   [junit4]   2> 263583 INFO  (coreCloseExecutor-789-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 263584 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 263585 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6b3ebb{/solr,null,UNAVAILABLE}
   [junit4]   2> 263588 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@1ad978e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 263588 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1335603
   [junit4]   2> 263591 INFO  (coreCloseExecutor-791-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@8b05b8
   [junit4]   2> 263595 INFO  (coreCloseExecutor-791-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 263596 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 263596 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[5125C27F85508D6D]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@e9b3ea{/solr,null,UNAVAILABLE}
   [junit4]   2> 263599 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartup
   [junit4]   2> 263601 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\collection1
   [junit4]   2> 263617 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 263620 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17ae60d{/solr,null,AVAILABLE}
   [junit4]   2> 263621 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1976d79{HTTP/1.1,[http/1.1]}{127.0.0.1:53123}
   [junit4]   2> 263621 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server Started @271704ms
   [junit4]   2> 263621 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\collection1\data, hostContext=/solr, hostPort=53123}
   [junit4]   2> 263621 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 263622 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.4.0
   [junit4]   2> 263622 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 263622 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 263622 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-04T13:15:20.250Z
   [junit4]   2> 263623 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\solr.xml
   [junit4]   2> 263649 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 263676 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\.
   [junit4]   2> 263676 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 263750 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 263765 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 263774 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 263789 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\.\collection1
   [junit4]   2> 263789 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\.\collection1\data\]
   [junit4]   2> 263792 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2007862301, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 263831 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 263831 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 263837 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=65.259765625, floorSegmentMB=1.0771484375, forceMergeDeletesPctAllowed=15.272702382954153, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 263841 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f4bc67[collection1] main]
   [junit4]   2> 263841 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-003\collection1\conf
   [junit4]   2> 263844 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 263844 INFO  (coreLoadExecutor-798-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 263847 INFO  (searcherExecutor-799-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f4bc67[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 264288 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-004\collection1
   [junit4]   2> 264298 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 264299 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f46deb{/solr,null,AVAILABLE}
   [junit4]   2> 264300 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1fc2fb{HTTP/1.1,[http/1.1]}{127.0.0.1:53126}
   [junit4]   2> 264300 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.e.j.s.Server Started @272383ms
   [junit4]   2> 264300 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5125C27F85508D6D-001\solr-instance-004\collection1\data, hostContext=/solr, hostPort=53126}
   [junit4]   2> 264300 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[5125C27F85508D6D]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 264300 INFO  (TEST-TestReplicationHandler.doTestReplicateAfter

[...truncated too long message...]

sTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 400087 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@192c82d
   [junit4]   2> 400525 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f2cdac[collection1] main]
   [junit4]   2> 400525 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 400525 INFO  (searcherExecutor-2249-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f2cdac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.4.0):C300) Uninverting(_8(6.4.0):C300) Uninverting(_7(6.4.0):C100) Uninverting(_9(6.4.0):C100) Uninverting(_a(6.4.0):C100) Uninverting(_c(6.4.0):C100)))}
   [junit4]   2> 400527 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 400580 WARN  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 400580 WARN  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergeFactor> is deprecated, configure it on the relevant <mergePolicyFactory> instead.
   [junit4]   2> 400580 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 400589 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 400670 WARN  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 400672 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 400687 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 400688 WARN  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 400694 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4843b9
   [junit4]   2> 400695 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.m.SolrMetricManager Replacing existing reporter 'default' in registry 'solr.core.collection1': [org.apache.solr.metrics.reporters.SolrJmxReporter@871c3c: domain = solr.core.collection1, service url = null, agent id = null]
   [junit4]   2> 400697 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.SolrIndexMetricsTest_5125C27F85508D6D-001\init-core-data-001\]
   [junit4]   2> 400711 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 400711 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 400712 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a6a42e[collection1] main]
   [junit4]   2> 400712 WARN  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
   [junit4]   2> 400712 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 400713 INFO  (searcherExecutor-2253-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a6a42e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.4.0):C300) Uninverting(_8(6.4.0):C300) Uninverting(_7(6.4.0):C100) Uninverting(_9(6.4.0):C100) Uninverting(_a(6.4.0):C100) Uninverting(_c(6.4.0):C100)))}
   [junit4]   2> 401957 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 402608 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11c0e35[collection1] main]
   [junit4]   2> 402608 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@62fe07
   [junit4]   2> 402609 INFO  (searcherExecutor-2253-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11c0e35[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.4.0):C500) Uninverting(_d(6.4.0):C500)))}
   [junit4]   2> 402609 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 402610 INFO  (TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[5125C27F85508D6D]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testIndexMetrics
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrIndexMetricsTest -Dtests.method=testIndexMetrics -Dtests.seed=5125C27F85508D6D -Dtests.slow=true -Dtests.locale=tr -Dtests.timezone=Etc/GMT-8 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 4.72s J0 | SolrIndexMetricsTest.testIndexMetrics <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([5125C27F85508D6D:9DF5FFC345DE7656]:0)
   [junit4]    > 	at org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics(SolrIndexMetricsTest.java:66)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 402611 INFO  (SUITE-SolrIndexMetricsTest-seed#[5125C27F85508D6D]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 402611 INFO  (SUITE-SolrIndexMetricsTest-seed#[5125C27F85508D6D]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=27367054
   [junit4]   2> 402612 INFO  (coreCloseExecutor-2257-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@19d241c
   [junit4]   2> 402613 INFO  (coreCloseExecutor-2257-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 402613 INFO  (SUITE-SolrIndexMetricsTest-seed#[5125C27F85508D6D]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.update.SolrIndexMetricsTest_5125C27F85508D6D-001
   [junit4]   2> NOTE: test params are: codec=SimpleText, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=tr, timezone=Etc/GMT-8
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_112 (32-bit)/cpus=3,threads=1,free=105575032,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [BasicDistributedZkTest, TestTrackingShardHandlerFactory, SolrTestCaseJ4Test, TestPseudoReturnFields, TestFieldCacheSortRandom, AnalysisErrorHandlingTest, TestCustomStream, ConnectionManagerTest, TestExportWriter, TestInitParams, TestSolr4Spatial2, DistributedFacetPivotSmallTest, TestRandomDVFaceting, ClassificationUpdateProcessorIntegrationTest, TestConfigSetImmutable, SharedFSAutoReplicaFailoverUtilsTest, DeleteLastCustomShardedReplicaTest, DirectSolrSpellCheckerTest, OpenExchangeRatesOrgProviderTest, JvmMetricsTest, DateFieldTest, HighlighterTest, RequiredFieldsTest, HardAutoCommitTest, SimpleFacetsTest, HttpSolrCallGetCoreTest, TestReplicationHandler, TestTolerantSearch, SSLMigrationTest, TestSolrConfigHandlerCloud, AlternateDirectoryTest, TestClassicSimilarityFactory, RuleEngineTest, TestGraphMLResponseWriter, DistribDocExpirationUpdateProcessorTest, SolrIndexMetricsTest]
   [junit4] Completed [75/676 (2!)] on J0 in 5.11s, 1 test, 1 failure <<< FAILURES!

[...truncated 56608 lines...]


Mime
View raw message