You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/05 12:11:47 UTC
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build #
496 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/496/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([8A116F9B1EED16C1:7D6281C3D805B927]: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.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1331)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
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:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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 10680 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_8A116F9B1EED16C1-001\init-core-data-001
[junit4] 2> 59716 INFO (SUITE-TestReplicationHandler-seed#[8A116F9B1EED16C1]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 59721 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 59723 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\collection1
[junit4] 2> 59765 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 59767 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5b59b{/solr,null,AVAILABLE}
[junit4] 2> 59771 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@166af30{HTTP/1.1,[http/1.1]}{127.0.0.1:50441}
[junit4] 2> 59771 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server Started @63722ms
[junit4] 2> 59771 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=50441}
[junit4] 2> 59772 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 59772 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 59772 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 59772 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-05T11:03:12.799Z
[junit4] 2> 59773 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\solr.xml
[junit4] 2> 59822 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 59830 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\.
[junit4] 2> 59833 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 59894 INFO (coreLoadExecutor-50-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 59920 INFO (coreLoadExecutor-50-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 59935 INFO (coreLoadExecutor-50-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 59950 INFO (coreLoadExecutor-50-thread-1) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\.\collection1
[junit4] 2> 59950 INFO (coreLoadExecutor-50-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_8A116F9B1EED16C1-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_8A116F9B1EED16C1-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 59951 INFO (coreLoadExecutor-50-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4109965621852688]
[junit4] 2> 59970 INFO (coreLoadExecutor-50-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 59970 INFO (coreLoadExecutor-50-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 59970 INFO (coreLoadExecutor-50-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7911976019914969]
[junit4] 2> 59972 INFO (coreLoadExecutor-50-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@fa5f49[collection1] main]
[junit4] 2> 59973 INFO (coreLoadExecutor-50-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_8A116F9B1EED16C1-001\solr-instance-001\collection1\conf
[junit4] 2> 59974 INFO (coreLoadExecutor-50-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 59974 INFO (coreLoadExecutor-50-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 59974 INFO (searcherExecutor-51-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@fa5f49[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 60337 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\collection1
[junit4] 2> 60352 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 60354 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10b15a4{/solr,null,AVAILABLE}
[junit4] 2> 60356 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@d692ad{HTTP/1.1,[http/1.1]}{127.0.0.1:50444}
[junit4] 2> 60356 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server Started @64307ms
[junit4] 2> 60356 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=50444}
[junit4] 2> 60357 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 60357 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 60357 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 60357 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-05T11:03:13.384Z
[junit4] 2> 60358 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\solr.xml
[junit4] 2> 60370 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 60377 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\.
[junit4] 2> 60377 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 60399 INFO (coreLoadExecutor-60-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 60415 INFO (coreLoadExecutor-60-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 60418 INFO (coreLoadExecutor-60-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 60421 INFO (coreLoadExecutor-60-thread-1) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\.\collection1
[junit4] 2> 60421 INFO (coreLoadExecutor-60-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_8A116F9B1EED16C1-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_8A116F9B1EED16C1-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 60422 INFO (coreLoadExecutor-60-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4109965621852688]
[junit4] 2> 60435 INFO (coreLoadExecutor-60-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 60436 INFO (coreLoadExecutor-60-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 60436 INFO (coreLoadExecutor-60-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7911976019914969]
[junit4] 2> 60437 INFO (coreLoadExecutor-60-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@143691[collection1] main]
[junit4] 2> 60437 INFO (coreLoadExecutor-60-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_8A116F9B1EED16C1-001\solr-instance-002\collection1\conf
[junit4] 2> 60461 INFO (coreLoadExecutor-60-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 60461 INFO (searcherExecutor-61-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@143691[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 60467 INFO (qtp4393105-143) [ 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=1
[junit4] 2> 60467 INFO (indexFetcher-65-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 60469 INFO (indexFetcher-65-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 60469 INFO (indexFetcher-65-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 60881 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@d692ad{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 60881 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=30886875
[junit4] 2> 60882 INFO (coreCloseExecutor-66-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@14fef8
[junit4] 2> 60887 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@10b15a4{/solr,null,UNAVAILABLE}
[junit4] 2> 60892 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@166af30{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 60892 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=14847474
[junit4] 2> 60893 INFO (coreCloseExecutor-68-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@54426
[junit4] 2> 60896 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5b59b{/solr,null,UNAVAILABLE}
[junit4] 2> 60901 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 60902 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cf7cc0{/solr,null,AVAILABLE}
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@cf7143{HTTP/1.1,[http/1.1]}{127.0.0.1:50460}
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server Started @64854ms
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=50460}
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-05T11:03:13.931Z
[junit4] 2> 60904 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\solr.xml
[junit4] 2> 60925 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 60930 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\.
[junit4] 2> 60930 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 60959 INFO (coreLoadExecutor-75-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 60975 INFO (coreLoadExecutor-75-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 60984 INFO (coreLoadExecutor-75-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 60992 INFO (coreLoadExecutor-75-thread-1) [ ] 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_8A116F9B1EED16C1-001\solr-instance-001\.\collection1
[junit4] 2> 60993 INFO (coreLoadExecutor-75-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_8A116F9B1EED16C1-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_8A116F9B1EED16C1-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 60995 INFO (coreLoadExecutor-75-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4109965621852688]
[junit4] 2> 61016 INFO (coreLoadExecutor-75-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 61016 INFO (coreLoadExecutor-75-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 61016 INFO (coreLoadExecutor-75-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7911976019914969]
[junit4] 2> 61017 INFO (coreLoadExecutor-75-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c3f98a[collection1] main]
[junit4] 2> 61018 INFO (coreLoadExecutor-75-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_8A116F9B1EED16C1-001\solr-instance-001\collection1\conf
[junit4] 2> 61018 INFO (coreLoadExecutor-75-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 61018 INFO (coreLoadExecutor-75-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 61018 INFO (searcherExecutor-76-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c3f98a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 61437 INFO (qtp5459117-177) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 61438 INFO (qtp5459117-177) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 1
[junit4] 2> 61440 INFO (qtp5459117-179) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 61441 INFO (qtp5459117-179) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1bfdeb4
[junit4] 2> 61443 INFO (qtp5459117-179) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@453c48[collection1] main]
[junit4] 2> 61443 INFO (qtp5459117-179) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 61443 INFO (searcherExecutor-76-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@453c48[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 61444 INFO (qtp5459117-179) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 61450 INFO (qtp5459117-180) [ 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=5
[junit4] 2> 61452 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 61456 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 61457 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12b39ee{/solr,null,AVAILABLE}
[junit4] 2> 61458 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@18d374{HTTP/1.1,[http/1.1]}{127.0.0.1:50464}
[junit4] 2> 61459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server Started @65409ms
[junit4] 2> 61459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=50464}
[junit4] 2> 61459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 61459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 61459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 61459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-05T11:03:14.486Z
[junit4] 2> 61460 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\solr.xml
[junit4] 2> 61468 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 61473 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\.
[junit4] 2> 61473 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 61494 INFO (coreLoadExecutor-85-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 61509 INFO (coreLoadExecutor-85-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 61511 INFO (coreLoadExecutor-85-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 61514 INFO (coreLoadExecutor-85-thread-1) [ ] 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_8A116F9B1EED16C1-001\solr-instance-002\.\collection1
[junit4] 2> 61514 INFO (coreLoadExecutor-85-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_8A116F9B1EED16C1-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_8A116F9B1EED16C1-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 61516 INFO (coreLoadExecutor-85-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4109965621852688]
[junit4] 2> 61548 INFO (coreLoadExecutor-85-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 61548 INFO (coreLoadExecutor-85-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 61548 INFO (coreLoadExecutor-85-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7911976019914969]
[junit4] 2> 61548 INFO (coreLoadExecutor-85-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@cb3e99[collection1] main]
[junit4] 2> 61549 INFO (coreLoadExecutor-85-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_8A116F9B1EED16C1-001\solr-instance-002\collection1\conf
[junit4] 2> 61551 INFO (coreLoadExecutor-85-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 61551 INFO (searcherExecutor-86-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cb3e99[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 61554 INFO (qtp5459117-182) [ 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> 61555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 61555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1475665394468
[junit4] 2> 61555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 61555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 61561 INFO (qtp5459117-183) [ 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=5
[junit4] 2> 61562 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 1
[junit4] 2> 61565 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1114010616543232]
[junit4] 2> 61566 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 61566 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1be891e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@88564e)
[junit4] 2> 61597 INFO (qtp5459117-176) [ 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=9
[junit4] 2> 61608 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=100) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1be891e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@88564e)
[junit4] 2> 61612 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=842474407, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 61617 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 61617 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@26c718[collection1] main]
[junit4] 2> 61625 INFO (searcherExecutor-86-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26c718[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 61990 INFO (qtp8716294-196) [ 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=1
[junit4] 2> 61991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 62007 INFO (qtp5459117-177) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 62011 INFO (qtp5459117-177) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 62080 INFO (qtp5459117-180) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 62114 INFO (qtp5459117-180) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 62123 INFO (qtp5459117-180) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 62140 INFO (qtp5459117-180) [ ] o.a.s.c.CoreContainer Reloading 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_8A116F9B1EED16C1-001\solr-instance-001\.\collection1
[junit4] 2> 62140 INFO (qtp5459117-180) [ 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_8A116F9B1EED16C1-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_8A116F9B1EED16C1-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 62166 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 62166 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 62169 INFO (qtp5459117-180) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ba9d15[collection1] main]
[junit4] 2> 62170 INFO (qtp5459117-180) [ 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_8A116F9B1EED16C1-001\solr-instance-001\collection1\conf
[junit4] 2> 62170 INFO (qtp5459117-180) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 62170 INFO (qtp5459117-180) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 62171 INFO (searcherExecutor-92-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ba9d15[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 62171 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=21.80859375, floorSegmentMB=1.7236328125, forceMergeDeletesPctAllowed=28.800694792847317, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3971587907731665
[junit4] 2> 62173 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 62174 INFO (qtp5459117-180) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@28e2a3[collection1] main]
[junit4] 2> 62174 INFO (searcherExecutor-92-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@28e2a3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 62175 INFO (qtp5459117-180) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1b7dcbf
[junit4] 2> 62178 INFO (qtp5459117-180) [ x:collection1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=collection1&qt=/admin/cores&action=reload&wt=javabin&version=2} status=0 QTime=149
[junit4] 2> 62183 INFO (qtp5459117-182) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 62186 INFO (qtp5459117-176) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 62192 INFO (qtp5459117-176) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 2
[junit4] 2> 62196 INFO (qtp5459117-177) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 62200 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 62200 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1fa57a9
[junit4] 2> 62233 INFO (qtp5459117-180) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@197a84e[collection1] main]
[junit4] 2> 62233 INFO (searcherExecutor-92-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@197a84e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c2)))}
[junit4] 2> 62233 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 62233 INFO (qtp5459117-180) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 33
[junit4] 2> 62279 INFO (qtp5459117-181) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=42
[junit4] 2> 62280 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 2 docs
[junit4] 2> 62284 INFO (qtp8716294-197) [ 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> 62285 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 62393 INFO (qtp8716294-198) [ 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> 62394 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 62496 INFO (qtp8716294-198) [ 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> 62496 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 62554 INFO (qtp5459117-183) [ 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> 62555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 62555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1475665395227
[junit4] 2> 62555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 62555 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 62560 INFO (qtp5459117-182) [ 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=4
[junit4] 2> 62562 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 62564 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=82.0185546875, floorSegmentMB=0.8720703125, forceMergeDeletesPctAllowed=15.184240985305134, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 62564 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 62565 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@c4d35f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@694273)
[junit4] 2> 62571 INFO (qtp5459117-179) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 62576 INFO (qtp5459117-176) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 62580 INFO (qtp5459117-177) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 62584 INFO (qtp5459117-180) [ 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> 62585 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2041) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@c4d35f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@694273)
[junit4] 2> 62589 WARN (indexFetcher-90-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics
[junit4] 2> java.io.IOException: file "replication.properties" was already written to
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:683)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:495)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:254)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$2(ReplicationHandler.java:1147)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 62590 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3135508579049067]
[junit4] 2> 62591 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 62598 INFO (indexFetcher-90-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@293e51[collection1] main]
[junit4] 2> 62615 INFO (searcherExecutor-86-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@293e51[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c2)))}
[junit4] 2> 62617 INFO (qtp8716294-200) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 62618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waited for 300ms and found 2 docs
[junit4] 2> 62618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 62619 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@cf7143{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 62619 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15286620
[junit4] 2> 62620 INFO (coreCloseExecutor-97-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@10c0880
[junit4] 2> 62623 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@cf7cc0{/solr,null,UNAVAILABLE}
[junit4] 2> 62627 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@18d374{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 62627 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=8133797
[junit4] 2> 62628 INFO (coreCloseExecutor-99-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f32442
[junit4] 2> 62633 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@12b39ee{/solr,null,UNAVAILABLE}
[junit4] 2> 62639 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterWrite2Slave
[junit4] 2> 62641 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-003\collection1
[junit4] 2> 62655 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 62656 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@693df6{/solr,null,AVAILABLE}
[junit4] 2> 62658 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@189e935{HTTP/1.1,[http/1.1]}{127.0.0.1:50470}
[junit4] 2> 62658 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server Started @66609ms
[junit4] 2> 62658 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-003\collection1\data, hostContext=/solr, hostPort=50470}
[junit4] 2> 62658 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 62658 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 62658 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 62658 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-05T11:03:15.685Z
[junit4] 2> 62660 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-003\solr.xml
[junit4] 2> 62680 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 62689 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-003\.
[junit4] 2> 62689 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 62746 INFO (coreLoadExecutor-106-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 62766 INFO (coreLoadExecutor-106-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 62772 INFO (coreLoadExecutor-106-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 62783 INFO (coreLoadExecutor-106-thread-1) [ ] 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_8A116F9B1EED16C1-001\solr-instance-003\.\collection1
[junit4] 2> 62784 INFO (coreLoadExecutor-106-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_8A116F9B1EED16C1-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_8A116F9B1EED16C1-001\solr-instance-003\.\collection1\data\]
[junit4] 2> 62785 INFO (coreLoadExecutor-106-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4109965621852688]
[junit4] 2> 62805 INFO (coreLoadExecutor-106-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 62805 INFO (coreLoadExecutor-106-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 62805 INFO (coreLoadExecutor-106-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7911976019914969]
[junit4] 2> 62806 INFO (coreLoadExecutor-106-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@253b22[collection1] main]
[junit4] 2> 62806 INFO (coreLoadExecutor-106-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_8A116F9B1EED16C1-001\solr-instance-003\collection1\conf
[junit4] 2> 62807 INFO (coreLoadExecutor-106-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 62807 INFO (coreLoadExecutor-106-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 62807 INFO (searcherExecutor-107-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@253b22[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 63193 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-004\collection1
[junit4] 2> 63212 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 63214 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@bdb4c1{/solr,null,AVAILABLE}
[junit4] 2> 63217 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1aaa7d{HTTP/1.1,[http/1.1]}{127.0.0.1:50485}
[junit4] 2> 63217 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.Server Started @67168ms
[junit4] 2> 63218 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-004\collection1\data, hostContext=/solr, hostPort=50485}
[junit4] 2> 63218 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0
[junit4] 2> 63218 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 63218 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 63218 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-05T11:03:16.245Z
[junit4] 2> 63220 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-004\solr.xml
[junit4] 2> 63232 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 63245 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] 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_8A116F9B1EED16C1-001\solr-instance-004\.
[junit4] 2> 63245 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 63275 INFO (coreLoadExecutor-116-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 63292 INFO (coreLoadExecutor-116-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 63297 INFO (coreLoadExecutor-116-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 63302 INFO (coreLoadExecutor-116-thread-1) [ ] 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_8A116F9B1EED16C1-001\solr-instance-004\.\collection1
[junit4] 2> 63302 INFO (coreLoadExecutor-116-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_8A116F9B1EED16C1-001\solr-instance-004\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_8A116F9B1EED16C1-001\solr-instance-004\.\collection1\data\]
[junit4] 2> 63304 INFO (coreLoadExecutor-116-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4109965621852688]
[junit4] 2> 63321 INFO (coreLoadExecutor-116-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 63321 INFO (coreLoadExecutor-116-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 63321 INFO (coreLoadExecutor-116-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7911976019914969]
[junit4] 2> 63322 INFO (coreLoadExecutor-116-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@eed316[collection1] main]
[junit4] 2> 63322 INFO (coreLoadExecutor-116-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_8A116F9B1EED16C1-001\solr-instance-004\collection1\conf
[junit4] 2> 63325 INFO (coreLoadExecutor-116-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 63326 INFO (searcherExecutor-117-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@eed316[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 63759 INFO (qtp30201129-221) [ 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=1
[junit4] 2> 63765 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 3
[junit4] 2> 63770 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 63774 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 63777 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 63780 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 63785 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 63789 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 63792 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 63796 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 63799 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 1
[junit4] 2> 63802 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 63806 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 63808 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 63809 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 63812 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 63815 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 63819 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 63822 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 63824 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 63830 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 63834 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 63836 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 63841 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 63843 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 63845 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 63848 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 63855 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 63858 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 63864 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 63868 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 63871 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 63875 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 63882 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 1
[junit4] 2> 63889 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 63892 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 63897 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 63904 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 63910 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 63914 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 63917 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 63920 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 63924 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 63929 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 63932 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 1
[junit4] 2> 63936 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 63940 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 63943 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 63948 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 63952 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 63955 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 63958 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 63965 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 63969 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 63972 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 63977 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 63983 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 63991 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 63995 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 64000 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 64002 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 64005 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 64007 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 64009 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 64011 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 64015 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 64019 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 64021 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 64022 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 64025 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 64027 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 64033 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 64035 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 64038 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 64060 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 64064 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 64066 INFO (qtp30201129-225) [ 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> 64067 INFO (indexFetcher-121-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 64067 INFO (indexFetcher-121-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 64067 INFO (indexFetcher-121-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 64067 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 64071 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 64073 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 64077 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 64080 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 64083 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 64087 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 64091 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 64094 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 64097 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 64101 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 64106 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 64110 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 64113 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 64117 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 64119 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 64123 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 64127 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 64131 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 64145 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 64149 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 64153 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 64156 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 64159 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 64163 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 64166 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 64169 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 64173 INFO (qtp30201129-223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 1
[junit4] 2> 64176 INFO (qtp30201129-226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 64179 INFO (qtp30201129-224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 64190 INFO (qtp30201129-221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 64207 INFO (qtp30201129-225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 13
[junit4] 2> 64212 INFO (qtp30201129-219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 1
[junit4] 2> 64216 INFO (qtp30201129-222) [ x:collection1] o.a.s.u.p.Log
[...truncated too long message...]
d-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 165096 INFO (coreLoadExecutor-683-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d881d[collection1] main]
[junit4] 2> 165097 INFO (coreLoadExecutor-683-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_8A116F9B1EED16C1-001\solr-instance-030\collection1\conf
[junit4] 2> 165097 INFO (coreLoadExecutor-683-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 165097 INFO (searcherExecutor-684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1d881d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 165551 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 165559 INFO (qtp30221804-1116) [ 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> 165560 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 165560 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1475665497492
[junit4] 2> 165560 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 165560 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 165564 INFO (qtp30221804-1119) [ 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=2
[junit4] 2> 165564 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 165570 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=21.80859375, floorSegmentMB=1.7236328125, forceMergeDeletesPctAllowed=28.800694792847317, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3971587907731665
[junit4] 2> 165571 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 165572 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to NRTCachingDirectory(SimpleFSDirectory@C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_8A116F9B1EED16C1-001\solr-instance-030\collection1\data\index.20161005130458592 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ed413; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4] 2> 165576 INFO (qtp30221804-1120) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 165580 INFO (qtp30221804-1120) [ 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> 165584 INFO (qtp30221804-1120) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 171107 INFO (qtp30221804-1123) [ 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> 171108 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=579349) : 5 secs (115869 bytes/sec) to NRTCachingDirectory(SimpleFSDirectory@C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_8A116F9B1EED16C1-001\solr-instance-030\collection1\data\index.20161005130458592 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ed413; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4] 2> 171121 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 171124 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 171125 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@162164f[collection1] main]
[junit4] 2> 171126 INFO (searcherExecutor-684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@162164f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c92)))}
[junit4] 2> 171127 INFO (qtp30066600-1134) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:51100/solr/collection1/replication&command=fetchindex} status=0 QTime=5576
[junit4] 2> 171134 INFO (qtp30066600-1138) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=92 status=0 QTime=2
[junit4] 2> 171136 INFO (qtp30221804-1118) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 3
[junit4] 2> 171137 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 92 docs
[junit4] 2> 171138 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.a.s.h.TestReplicationHandler approximateTimeInSeconds = 0.0 timeTakenInSeconds = 5
[junit4] 2> 171138 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRateLimitedReplication
[junit4] 2> 171139 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@17ca860{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 171139 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=32238935
[junit4] 2> 171140 INFO (coreCloseExecutor-689-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@8092a3
[junit4] 2> 171159 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b26a7d{/solr,null,UNAVAILABLE}
[junit4] 2> 171162 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@15b842f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 171162 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=17005777
[junit4] 2> 171163 INFO (coreCloseExecutor-691-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@573e74
[junit4] 2> 171166 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8A116F9B1EED16C1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@db8ce8{/solr,null,UNAVAILABLE}
[junit4] 2> 171171 INFO (SUITE-TestReplicationHandler-seed#[8A116F9B1EED16C1]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[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.handler.TestReplicationHandler_8A116F9B1EED16C1-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {name=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneVarGapFixedInterval), newname=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, maxPointsInLeafNode=1756, maxMBSortInHeap=6.110058469847468, sim=ClassicSimilarity, locale=cs, timezone=Europe/Vienna
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=1,free=105064832,total=311427072
[junit4] 2> NOTE: All tests run in this JVM: [DistribDocExpirationUpdateProcessorTest, FacetPivotSmallTest, TestReplicationHandler]
[junit4] Completed [13/637 (1!)] on J0 in 111.53s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 54674 lines...]