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...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to