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/12/10 03:24:18 UTC
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_112) - Build #
612 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/612/
Java: 32bit/jdk1.8.0_112 -server -XX:+UseParallelGC
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([EDCE5BA4B1049DEF:1ABDB5FC77EC3209]: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:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11019 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_EDCE5BA4B1049DEF-001\init-core-data-001
[junit4] 2> 258815 INFO (SUITE-TestReplicationHandler-seed#[EDCE5BA4B1049DEF]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 258817 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRateLimitedReplication
[junit4] 2> 258817 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\collection1
[junit4] 2> 258834 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 258835 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e6599f{/solr,null,AVAILABLE}
[junit4] 2> 258840 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@12276ea{HTTP/1.1,[http/1.1]}{127.0.0.1:53679}
[junit4] 2> 258840 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server Started @263336ms
[junit4] 2> 258840 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=53679}
[junit4] 2> 258840 ERROR (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 258841 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 258841 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 258841 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 258841 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-10T02:08:30.655Z
[junit4] 2> 258842 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\solr.xml
[junit4] 2> 258853 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 258859 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\.
[junit4] 2> 258859 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 258886 WARN (coreLoadExecutor-1530-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 258887 INFO (coreLoadExecutor-1530-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 258895 INFO (coreLoadExecutor-1530-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 258901 INFO (coreLoadExecutor-1530-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 258912 INFO (coreLoadExecutor-1530-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_EDCE5BA4B1049DEF-001\solr-instance-001\.\collection1
[junit4] 2> 258912 INFO (coreLoadExecutor-1530-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_EDCE5BA4B1049DEF-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_EDCE5BA4B1049DEF-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 258926 INFO (coreLoadExecutor-1530-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=69.744140625, floorSegmentMB=1.4365234375, forceMergeDeletesPctAllowed=16.72399132501213, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 258953 INFO (coreLoadExecutor-1530-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 258953 INFO (coreLoadExecutor-1530-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 258953 INFO (coreLoadExecutor-1530-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.41849385311882215]
[junit4] 2> 258954 INFO (coreLoadExecutor-1530-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@568657[collection1] main]
[junit4] 2> 258954 INFO (coreLoadExecutor-1530-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_EDCE5BA4B1049DEF-001\solr-instance-001\collection1\conf
[junit4] 2> 258960 INFO (coreLoadExecutor-1530-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 258960 INFO (coreLoadExecutor-1530-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 258960 INFO (searcherExecutor-1531-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@568657[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 259368 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-002\collection1
[junit4] 2> 259415 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 259417 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1128796{/solr,null,AVAILABLE}
[junit4] 2> 259419 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a1c595{HTTP/1.1,[http/1.1]}{127.0.0.1:53691}
[junit4] 2> 259419 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server Started @263915ms
[junit4] 2> 259419 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=53691}
[junit4] 2> 259419 ERROR (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 259424 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 259424 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 259424 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 259424 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-10T02:08:31.238Z
[junit4] 2> 259425 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-002\solr.xml
[junit4] 2> 259455 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 259457 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-002\.
[junit4] 2> 259462 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 259493 WARN (coreLoadExecutor-1540-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 259494 INFO (coreLoadExecutor-1540-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 259504 INFO (coreLoadExecutor-1540-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 259505 INFO (coreLoadExecutor-1540-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 259505 INFO (coreLoadExecutor-1540-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_EDCE5BA4B1049DEF-001\solr-instance-002\.\collection1
[junit4] 2> 259509 INFO (coreLoadExecutor-1540-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_EDCE5BA4B1049DEF-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_EDCE5BA4B1049DEF-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 259518 INFO (coreLoadExecutor-1540-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=69.744140625, floorSegmentMB=1.4365234375, forceMergeDeletesPctAllowed=16.72399132501213, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 259531 INFO (coreLoadExecutor-1540-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 259531 INFO (coreLoadExecutor-1540-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 259531 INFO (coreLoadExecutor-1540-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.41849385311882215]
[junit4] 2> 259532 INFO (coreLoadExecutor-1540-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ddb23[collection1] main]
[junit4] 2> 259533 INFO (coreLoadExecutor-1540-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_EDCE5BA4B1049DEF-001\solr-instance-002\collection1\conf
[junit4] 2> 259538 INFO (coreLoadExecutor-1540-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 259538 INFO (searcherExecutor-1541-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ddb23[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 259544 INFO (qtp22157174-3460) [ 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> 259561 INFO (indexFetcher-1545-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 259561 INFO (indexFetcher-1545-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 259561 INFO (indexFetcher-1545-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 259966 INFO (qtp22157174-3463) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 259966 INFO (qtp22157174-3463) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 259968 INFO (qtp11060247-3481) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 259969 INFO (qtp11060247-3481) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 259971 INFO (qtp22157174-3466) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 259971 INFO (qtp22157174-3466) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b6e6e1
[junit4] 2> 259975 INFO (qtp22157174-3466) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c099c[collection1] main]
[junit4] 2> 259975 INFO (qtp22157174-3466) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 259975 INFO (searcherExecutor-1531-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c099c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 259975 INFO (qtp22157174-3466) [ 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> 259976 INFO (qtp11060247-3480) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 259976 INFO (qtp11060247-3480) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@13711ef
[junit4] 2> 259977 INFO (qtp11060247-3480) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@cde782[collection1] main]
[junit4] 2> 259977 INFO (qtp11060247-3480) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 259977 INFO (searcherExecutor-1541-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cde782[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 259978 INFO (qtp11060247-3480) [ 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 1
[junit4] 2> 259979 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@12276ea{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 259979 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=873179
[junit4] 2> 259979 INFO (coreCloseExecutor-1546-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1187005
[junit4] 2> 259981 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1e6599f{/solr,null,UNAVAILABLE}
[junit4] 2> 259982 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1a1c595{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 259983 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=8832824
[junit4] 2> 259985 INFO (coreCloseExecutor-1548-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@154e8b4
[junit4] 2> 259989 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1128796{/solr,null,UNAVAILABLE}
[junit4] 2> 259998 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 260016 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19291d2{/solr,null,AVAILABLE}
[junit4] 2> 260017 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4808f8{HTTP/1.1,[http/1.1]}{127.0.0.1:53733}
[junit4] 2> 260017 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server Started @264513ms
[junit4] 2> 260018 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=53733}
[junit4] 2> 260018 ERROR (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 260024 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 260024 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 260024 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 260024 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-10T02:08:31.838Z
[junit4] 2> 260028 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\solr.xml
[junit4] 2> 260034 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 260037 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\.
[junit4] 2> 260039 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 260052 WARN (coreLoadExecutor-1555-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 260053 INFO (coreLoadExecutor-1555-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 260060 INFO (coreLoadExecutor-1555-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 260062 INFO (coreLoadExecutor-1555-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 260064 INFO (coreLoadExecutor-1555-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_EDCE5BA4B1049DEF-001\solr-instance-001\.\collection1
[junit4] 2> 260104 INFO (coreLoadExecutor-1555-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_EDCE5BA4B1049DEF-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_EDCE5BA4B1049DEF-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 260111 INFO (coreLoadExecutor-1555-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@d352c5
[junit4] 2> 260122 INFO (coreLoadExecutor-1555-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 260122 INFO (coreLoadExecutor-1555-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 260122 INFO (coreLoadExecutor-1555-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=13.09765625, floorSegmentMB=1.2412109375, forceMergeDeletesPctAllowed=19.52831167500799, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.175886814924909
[junit4] 2> 260123 INFO (coreLoadExecutor-1555-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c4473[collection1] main]
[junit4] 2> 260124 INFO (coreLoadExecutor-1555-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_EDCE5BA4B1049DEF-001\solr-instance-001\collection1\conf
[junit4] 2> 260124 INFO (coreLoadExecutor-1555-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 260133 INFO (searcherExecutor-1556-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c4473[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 260551 INFO (qtp28237393-3507) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 2
[junit4] 2> 260553 INFO (qtp28237393-3505) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 260555 INFO (qtp28237393-3504) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 260557 INFO (qtp28237393-3504) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 260560 INFO (qtp28237393-3504) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 260562 INFO (qtp28237393-3504) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 260564 INFO (qtp28237393-3504) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 260566 INFO (qtp28237393-3515) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 260568 INFO (qtp28237393-3510) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 260569 INFO (qtp28237393-3507) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 260571 INFO (qtp28237393-3505) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 260572 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 260573 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 260574 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 260577 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 260580 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 260583 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 260584 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 260586 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 1
[junit4] 2> 260588 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 260589 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 260592 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 260594 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 260595 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 260597 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 260599 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 260600 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 260602 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 260604 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 260605 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 260608 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 260610 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 260611 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 260612 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 260613 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 260615 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 260617 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 260619 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 260620 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 260622 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 260623 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 260624 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 260625 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 260626 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 260631 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 1
[junit4] 2> 260633 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 1
[junit4] 2> 260635 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 260639 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 2
[junit4] 2> 260640 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 260642 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 260643 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 260644 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 260645 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 260646 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 260647 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 260648 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 260649 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 260652 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 260653 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 260654 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 260655 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 260658 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 260659 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 260660 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 260662 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 260664 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 260665 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 260666 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 260667 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 260668 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 260669 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 260670 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 260672 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 260673 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 260674 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 260676 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 260677 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 260678 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 260679 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 260680 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 260682 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 260683 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 260685 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 260686 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 260690 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 1
[junit4] 2> 260691 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 260692 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 260694 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 260696 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 260697 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 260698 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 260700 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 260701 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 260702 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 260703 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 260704 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 260705 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 260707 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 260708 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 260708 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4c4826
[junit4] 2> 260733 INFO (qtp28237393-3503) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1700014[collection1] main]
[junit4] 2> 260733 INFO (qtp28237393-3503) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 260736 INFO (searcherExecutor-1556-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1700014[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C98)))}
[junit4] 2> 260736 INFO (qtp28237393-3503) [ 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 27
[junit4] 2> 260737 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4808f8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 260737 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=22657938
[junit4] 2> 260830 INFO (coreCloseExecutor-1560-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@19b2d38
[junit4] 2> 260835 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@19291d2{/solr,null,UNAVAILABLE}
[junit4] 2> 260852 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 260855 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d643f{/solr,null,AVAILABLE}
[junit4] 2> 260858 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1875f78{HTTP/1.1,[http/1.1]}{127.0.0.1:53785}
[junit4] 2> 260858 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server Started @265354ms
[junit4] 2> 260858 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=53785}
[junit4] 2> 260863 ERROR (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 260864 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 260865 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 260865 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 260865 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-10T02:08:32.679Z
[junit4] 2> 260867 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\solr.xml
[junit4] 2> 260884 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 260892 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-001\.
[junit4] 2> 260892 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 260930 WARN (coreLoadExecutor-1567-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 260931 INFO (coreLoadExecutor-1567-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 260939 INFO (coreLoadExecutor-1567-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 260943 INFO (coreLoadExecutor-1567-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 260951 INFO (coreLoadExecutor-1567-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_EDCE5BA4B1049DEF-001\solr-instance-001\.\collection1
[junit4] 2> 260952 INFO (coreLoadExecutor-1567-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_EDCE5BA4B1049DEF-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_EDCE5BA4B1049DEF-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 260969 INFO (coreLoadExecutor-1567-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 260969 INFO (coreLoadExecutor-1567-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 260971 INFO (coreLoadExecutor-1567-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@891abc
[junit4] 2> 260974 INFO (coreLoadExecutor-1567-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@16b375e[collection1] main]
[junit4] 2> 260974 INFO (coreLoadExecutor-1567-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_EDCE5BA4B1049DEF-001\solr-instance-001\collection1\conf
[junit4] 2> 260974 INFO (coreLoadExecutor-1567-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 260975 INFO (searcherExecutor-1568-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16b375e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C98)))}
[junit4] 2> 261401 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 261403 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6dcc65{/solr,null,AVAILABLE}
[junit4] 2> 261407 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a02256{HTTP/1.1,[http/1.1]}{127.0.0.1:53795}
[junit4] 2> 261407 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server Started @265902ms
[junit4] 2> 261413 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=53795}
[junit4] 2> 261421 ERROR (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 261429 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 261429 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 261429 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 261429 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-10T02:08:33.243Z
[junit4] 2> 261430 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-002\solr.xml
[junit4] 2> 261437 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 261447 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-002\.
[junit4] 2> 261448 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 261470 WARN (coreLoadExecutor-1577-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 261472 INFO (coreLoadExecutor-1577-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 261490 INFO (coreLoadExecutor-1577-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 261492 INFO (coreLoadExecutor-1577-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 261494 INFO (coreLoadExecutor-1577-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_EDCE5BA4B1049DEF-001\solr-instance-002\.\collection1
[junit4] 2> 261495 INFO (coreLoadExecutor-1577-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_EDCE5BA4B1049DEF-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_EDCE5BA4B1049DEF-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 261501 INFO (coreLoadExecutor-1577-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@1660d15
[junit4] 2> 261513 INFO (coreLoadExecutor-1577-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 261513 INFO (coreLoadExecutor-1577-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 261514 INFO (coreLoadExecutor-1577-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=13.09765625, floorSegmentMB=1.2412109375, forceMergeDeletesPctAllowed=19.52831167500799, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.175886814924909
[junit4] 2> 261514 INFO (coreLoadExecutor-1577-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1832cf5[collection1] main]
[junit4] 2> 261514 INFO (coreLoadExecutor-1577-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_EDCE5BA4B1049DEF-001\solr-instance-002\collection1\conf
[junit4] 2> 261515 INFO (coreLoadExecutor-1577-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 261515 INFO (searcherExecutor-1578-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1832cf5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 261996 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 262002 INFO (qtp768694-3536) [ 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> 262002 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 262002 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1481335712522
[junit4] 2> 262002 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 262002 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 262009 INFO (qtp768694-3533) [ 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> 262009 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 262018 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@1a50629
[junit4] 2> 262019 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 262019 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_EDCE5BA4B1049DEF-001\solr-instance-002\collection1\data\index.20161210040833823 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@141d891; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4] 2> 262022 INFO (qtp768694-3532) [ 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> 262025 INFO (qtp768694-3531) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 262030 INFO (qtp768694-3530) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 264873 INFO (qtp768694-3529) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 264877 INFO (qtp768694-3528) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 264887 INFO (qtp768694-3538) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 264890 INFO (qtp768694-3526) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 267703 INFO (qtp768694-3536) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 267707 INFO (qtp768694-3530) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 267710 INFO (qtp768694-3529) [ 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> 267710 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=593806) : 5 secs (118761 bytes/sec) to NRTCachingDirectory(SimpleFSDirectory@C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_EDCE5BA4B1049DEF-001\solr-instance-002\collection1\data\index.20161210040833823 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@141d891; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4] 2> 267729 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=13.09765625, floorSegmentMB=1.2412109375, forceMergeDeletesPctAllowed=19.52831167500799, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.175886814924909
[junit4] 2> 267732 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 267734 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a0dc15[collection1] main]
[junit4] 2> 267747 INFO (searcherExecutor-1578-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a0dc15[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C98)))}
[junit4] 2> 267749 INFO (qtp3293409-3550) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:53785/solr/collection1/replication&command=fetchindex} status=0 QTime=5754
[junit4] 2> 267808 INFO (qtp3293409-3549) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=98 status=0 QTime=7
[junit4] 2> 267813 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 98 docs
[junit4] 2> 267813 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.h.TestReplicationHandler approximateTimeInSeconds = 0.0 timeTakenInSeconds = 5
[junit4] 2> 267813 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRateLimitedReplication
[junit4] 2> 267820 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1875f78{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 267820 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=28182636
[junit4] 2> 267823 INFO (coreCloseExecutor-1583-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1a047da
[junit4] 2> 267826 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5d643f{/solr,null,UNAVAILABLE}
[junit4] 2> 267834 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1a02256{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 267834 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18053423
[junit4] 2> 267835 INFO (coreCloseExecutor-1585-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@dfd71d
[junit4] 2> 267841 INFO (TEST-TestReplicationHandler.testRateLimitedReplication-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6dcc65{/solr,null,UNAVAILABLE}
[junit4] 2> 267845 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 267847 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-003\collection1
[junit4] 2> 267863 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 267864 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7def97{/solr,null,AVAILABLE}
[junit4] 2> 267865 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1274931{HTTP/1.1,[http/1.1]}{127.0.0.1:53845}
[junit4] 2> 267865 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server Started @272361ms
[junit4] 2> 267865 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-003\collection1\data, hostContext=/solr, hostPort=53845}
[junit4] 2> 267866 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 267866 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 267866 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 267866 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 267866 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-10T02:08:39.680Z
[junit4] 2> 267867 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-003\solr.xml
[junit4] 2> 267889 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 267897 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-003\.
[junit4] 2> 267897 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 267953 WARN (coreLoadExecutor-1592-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 267954 INFO (coreLoadExecutor-1592-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 267961 INFO (coreLoadExecutor-1592-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 267968 INFO (coreLoadExecutor-1592-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 267976 INFO (coreLoadExecutor-1592-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_EDCE5BA4B1049DEF-001\solr-instance-003\.\collection1
[junit4] 2> 267977 INFO (coreLoadExecutor-1592-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_EDCE5BA4B1049DEF-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_EDCE5BA4B1049DEF-001\solr-instance-003\.\collection1\data\]
[junit4] 2> 267980 INFO (coreLoadExecutor-1592-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=69.744140625, floorSegmentMB=1.4365234375, forceMergeDeletesPctAllowed=16.72399132501213, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 267993 INFO (coreLoadExecutor-1592-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 267993 INFO (coreLoadExecutor-1592-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 267993 INFO (coreLoadExecutor-1592-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.41849385311882215]
[junit4] 2> 267994 INFO (coreLoadExecutor-1592-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1cc045[collection1] main]
[junit4] 2> 267994 INFO (coreLoadExecutor-1592-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_EDCE5BA4B1049DEF-001\solr-instance-003\collection1\conf
[junit4] 2> 267995 INFO (coreLoadExecutor-1592-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 267995 INFO (coreLoadExecutor-1592-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 267996 INFO (searcherExecutor-1593-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1cc045[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 268401 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-004\collection1
[junit4] 2> 268411 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 268412 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22365f{/solr,null,AVAILABLE}
[junit4] 2> 268413 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1d674d1{HTTP/1.1,[http/1.1]}{127.0.0.1:53854}
[junit4] 2> 268413 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server Started @272909ms
[junit4] 2> 268413 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-004\collection1\data, hostContext=/solr, hostPort=53854}
[junit4] 2> 268415 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 268416 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 268416 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 268416 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 268416 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-10T02:08:40.230Z
[junit4] 2> 268418 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-004\solr.xml
[junit4] 2> 268424 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 268431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] 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_EDCE5BA4B1049DEF-001\solr-instance-004\.
[junit4] 2> 268431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 268445 WARN (coreLoadExecutor-1602-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 268446 INFO (coreLoadExecutor-1602-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 268454 INFO (coreLoadExecutor-1602-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 268456 INFO (coreLoadExecutor-1602-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 268459 INFO (coreLoadExecutor-1602-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_EDCE5BA4B1049DEF-001\solr-instance-004\.\collection1
[junit4] 2> 268459 INFO (coreLoadExecutor-1602-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_EDCE5BA4B1049DEF-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_EDCE5BA4B1049DEF-001\solr-instance-004\.\collection1\data\]
[junit4] 2> 268462 INFO (coreLoadExecutor-1602-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=69.744140625, floorSegmentMB=1.4365234375, forceMergeDeletesPctAllowed=16.72399132501213, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 268470 INFO (coreLoadExecutor-1602-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 268470 INFO (coreLoadExecutor-1602-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 268470 INFO (coreLoadExecutor-1602-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.41849385311882215]
[junit4] 2> 268470 INFO (coreLoadExecutor-1602-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d58315[collection1] main]
[junit4] 2> 268471 INFO (coreLoadExecutor-1602-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_EDCE5BA4B1049DEF-001\solr-instance-004\collection1\conf
[junit4] 2> 268472 INFO (coreLoadExecutor-1602-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 268473 INFO (searcherExecutor-1603-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d58315[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 268935 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1d674d1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 268935 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2762940
[junit4] 2> 268936 INFO (coreCloseExecutor-1608-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5f1e0c
[junit4] 2> 268938 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@22365f{/solr,null,UNAVAILABLE}
[junit4] 2> 268942 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1274931{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 268942 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=27776656
[junit4] 2> 268943 INFO (coreCloseExecutor-1610-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@44a335
[junit4] 2> 268945 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7def97{/solr,null,UNAVAILABLE}
[junit4] 2> 268949 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 268950 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@134a9b2{/solr,null,AVAILABLE}
[junit4] 2> 268952 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5666f{HTTP/1.1,[http/1.1]}{127.0.0.1:53857}
[junit4] 2> 268952 INFO (TEST-TestReplicationHandler.doTestReplicate
[...truncated too long message...]
lection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358346 INFO (qtp18445055-4538) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358349 INFO (qtp18445055-4537) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358352 INFO (qtp18445055-4533) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358355 INFO (qtp18445055-4534) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358357 INFO (qtp18445055-4531) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358360 INFO (qtp18445055-4535) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358363 INFO (qtp18445055-4536) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358367 INFO (qtp18445055-4538) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358370 INFO (qtp18445055-4537) [ 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> 358371 INFO (indexFetcher-2178-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1693) : 0 secs (null bytes/sec) to MockDirectoryWrapper(MMapDirectory@C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_EDCE5BA4B1049DEF-001\index-MMapDirectory-065 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1457630)
[junit4] 2> 358379 WARN (indexFetcher-2178-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:675)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:487)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
[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> 358380 INFO (indexFetcher-2178-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 358380 INFO (indexFetcher-2178-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358381 INFO (indexFetcher-2178-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12b1e2e[collection1] main]
[junit4] 2> 358381 INFO (searcherExecutor-2174-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12b1e2e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C493) Uninverting(_1(6.4.0):C1)))}
[junit4] 2> 358407 INFO (qtp912312-4553) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=494 status=0 QTime=0
[junit4] 2> 358407 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.h.TestReplicationHandler Waited for 900ms and found 494 docs
[junit4] 2> 358408 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestStopPoll
[junit4] 2> 358409 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1fc1d36{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 358409 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=25809955
[junit4] 2> 358410 INFO (coreCloseExecutor-2181-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@186c2d1
[junit4] 2> 358411 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b0cf6e{/solr,null,UNAVAILABLE}
[junit4] 2> 358412 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@10e4be3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 358412 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=27512521
[junit4] 2> 358413 INFO (coreCloseExecutor-2183-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1acc5b9
[junit4] 2> 358417 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[EDCE5BA4B1049DEF]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@45eb91{/solr,null,UNAVAILABLE}
[junit4] IGNOR/A 0.00s J0 | TestReplicationHandler.doTestIndexFetchOnMasterRestart
[junit4] > Assumption #1: 'awaitsfix' test group is disabled (@AwaitsFix(bugUrl=https://issues.apache.org/jira/browse/SOLR-9036))
[junit4] 2> 358419 INFO (SUITE-TestReplicationHandler-seed#[EDCE5BA4B1049DEF]-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_EDCE5BA4B1049DEF-001
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=9, maxDocsPerChunk=608, blockSize=3), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=9, blockSize=3)), sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=lv-LV, timezone=Europe/Uzhgorod
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_112 (32-bit)/cpus=3,threads=1,free=151872120,total=308805632
[junit4] 2> NOTE: All tests run in this JVM: [TestConfigSetsAPIExclusivity, TestSlowCompositeReaderWrapper, TestSQLHandlerNonCloud, DirectSolrSpellCheckerTest, DistributedMLTComponentTest, TestCSVLoader, CheckHdfsIndexTest, RecoveryAfterSoftCommitTest, ExitableDirectoryReaderTest, SparseHLLTest, TestCollationField, SpatialFilterTest, SimpleFacetsTest, TestConfigReload, BaseCdcrDistributedZkTest, TestPseudoReturnFields, TestSort, TestSurroundQueryParser, AnalysisAfterCoreReloadTest, ReplaceNodeTest, DirectUpdateHandlerTest, TestManagedSchemaAPI, PluginInfoTest, CloneFieldUpdateProcessorFactoryTest, TestSchemaManager, DistributedQueueTest, PeerSyncWithIndexFingerprintCachingTest, SOLR749Test, TestPerFieldSimilarity, TestPerFieldSimilarityWithDefaultOverride, TestSolrCloudSnapshots, TestShortCircuitedRequests, TestSweetSpotSimilarityFactory, TestSolrConfigHandlerConcurrent, SynonymTokenizerTest, TestLockTree, TestDynamicLoading, DisMaxRequestHandlerTest, PingRequestHandlerTest, TestExactSharedStatsCache, StandardRequestHandlerTest, TestXIncludeConfig, TestIBSimilarityFactory, ZkCLITest, SchemaVersionSpecificBehaviorTest, RequestLoggingTest, HLLSerializationTest, TestReplicationHandler]
[junit4] Completed [109/660 (1!)] on J0 in 99.63s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 56408 lines...]