You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/20 22:10:26 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3617
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3617/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -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([DBF6D87FFD10FC77:2C8536273BF85391]: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:1329)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10857 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/init-core-data-001
[junit4] 2> 300961 INFO (SUITE-TestReplicationHandler-seed#[DBF6D87FFD10FC77]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 300963 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 300963 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1
[junit4] 2> 300970 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 300972 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15920bff{/solr,null,AVAILABLE}
[junit4] 2> 300975 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2cf52d5a{HTTP/1.1,[http/1.1]}{127.0.0.1:52416}
[junit4] 2> 300975 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server Started @308732ms
[junit4] 2> 300975 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=52416}
[junit4] 2> 300975 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 300975 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 300975 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 300975 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 300975 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-20T20:43:06.852Z
[junit4] 2> 300976 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/solr.xml
[junit4] 2> 300987 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/.
[junit4] 2> 300987 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 300999 WARN (coreLoadExecutor-1680-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 300999 INFO (coreLoadExecutor-1680-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 301006 INFO (coreLoadExecutor-1680-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 301009 INFO (coreLoadExecutor-1680-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 301013 INFO (coreLoadExecutor-1680-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/./collection1
[junit4] 2> 301013 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/./collection1/data/]
[junit4] 2> 301014 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 301024 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 301024 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 301024 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=9.5244140625, floorSegmentMB=1.01953125, forceMergeDeletesPctAllowed=9.89560320307762, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 301024 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a7b4726[collection1] main]
[junit4] 2> 301024 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1/conf
[junit4] 2> 301025 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 301025 INFO (coreLoadExecutor-1680-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 301025 INFO (searcherExecutor-1681-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7a7b4726[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 301493 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/collection1
[junit4] 2> 301498 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 301500 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ce67c5c{/solr,null,AVAILABLE}
[junit4] 2> 301500 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Started ServerConnector@76ff05f8{HTTP/1.1,[http/1.1]}{127.0.0.1:52417}
[junit4] 2> 301500 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server Started @309258ms
[junit4] 2> 301500 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=52417}
[junit4] 2> 301501 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 301501 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 301501 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 301501 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 301501 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-20T20:43:07.378Z
[junit4] 2> 301502 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/solr.xml
[junit4] 2> 301513 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/.
[junit4] 2> 301514 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 301521 WARN (coreLoadExecutor-1690-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 301522 INFO (coreLoadExecutor-1690-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 301529 INFO (coreLoadExecutor-1690-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 301530 INFO (coreLoadExecutor-1690-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 301531 INFO (coreLoadExecutor-1690-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/./collection1
[junit4] 2> 301532 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/./collection1/data/]
[junit4] 2> 301532 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 301543 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 301543 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 301544 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=9.5244140625, floorSegmentMB=1.01953125, forceMergeDeletesPctAllowed=9.89560320307762, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 301552 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@157be7c5[collection1] main]
[junit4] 2> 301552 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/collection1/conf
[junit4] 2> 301554 INFO (coreLoadExecutor-1690-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 301555 INFO (searcherExecutor-1691-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@157be7c5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 301818 INFO (qtp416435622-2698) [ 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> 301819 INFO (indexFetcher-1695-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 301819 INFO (indexFetcher-1695-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 301819 INFO (indexFetcher-1695-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 302017 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@76ff05f8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 302017 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1322286638
[junit4] 2> 302018 INFO (coreCloseExecutor-1696-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@451856b9
[junit4] 2> 302020 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@ce67c5c{/solr,null,UNAVAILABLE}
[junit4] 2> 302023 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@2cf52d5a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 302023 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1452615861
[junit4] 2> 302023 INFO (coreCloseExecutor-1698-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4126a501
[junit4] 2> 302025 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@15920bff{/solr,null,UNAVAILABLE}
[junit4] 2> 302026 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 302028 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14280ec9{/solr,null,AVAILABLE}
[junit4] 2> 302029 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7ebf3f4f{HTTP/1.1,[http/1.1]}{127.0.0.1:52419}
[junit4] 2> 302029 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server Started @309787ms
[junit4] 2> 302029 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=52419}
[junit4] 2> 302029 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 302030 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 302030 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 302030 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 302030 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-20T20:43:07.907Z
[junit4] 2> 302030 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/solr.xml
[junit4] 2> 302076 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/.
[junit4] 2> 302077 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 302083 WARN (coreLoadExecutor-1705-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 302084 INFO (coreLoadExecutor-1705-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 302091 INFO (coreLoadExecutor-1705-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 302092 INFO (coreLoadExecutor-1705-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 302093 INFO (coreLoadExecutor-1705-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/./collection1
[junit4] 2> 302093 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/./collection1/data/]
[junit4] 2> 302094 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 302102 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 302102 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 302103 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=9.5244140625, floorSegmentMB=1.01953125, forceMergeDeletesPctAllowed=9.89560320307762, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 302103 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@45c7f762[collection1] main]
[junit4] 2> 302103 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1/conf
[junit4] 2> 302103 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 302103 INFO (coreLoadExecutor-1705-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 302104 INFO (searcherExecutor-1706-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@45c7f762[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 302586 INFO (qtp547513477-2736) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 302586 INFO (qtp547513477-2736) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 302587 INFO (qtp547513477-2737) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 302587 INFO (qtp547513477-2737) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@57a377e8
[junit4] 2> 302589 INFO (qtp547513477-2737) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@18599957[collection1] main]
[junit4] 2> 302589 INFO (qtp547513477-2737) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 302589 INFO (searcherExecutor-1706-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18599957[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 302589 INFO (qtp547513477-2737) [ 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 2
[junit4] 2> 302590 INFO (qtp547513477-2738) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 302590 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 302592 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 302594 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4fbc28{/solr,null,AVAILABLE}
[junit4] 2> 302594 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Started ServerConnector@133be59d{HTTP/1.1,[http/1.1]}{127.0.0.1:52421}
[junit4] 2> 302594 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server Started @310352ms
[junit4] 2> 302594 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=52421}
[junit4] 2> 302594 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 302595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 302595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 302595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 302595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-20T20:43:08.472Z
[junit4] 2> 302596 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/solr.xml
[junit4] 2> 302625 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/.
[junit4] 2> 302625 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 302633 WARN (coreLoadExecutor-1715-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 302638 INFO (coreLoadExecutor-1715-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 302647 INFO (coreLoadExecutor-1715-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 302648 INFO (coreLoadExecutor-1715-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 302649 INFO (coreLoadExecutor-1715-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/./collection1
[junit4] 2> 302649 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/./collection1/data/]
[junit4] 2> 302652 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 302660 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 302660 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 302660 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=9.5244140625, floorSegmentMB=1.01953125, forceMergeDeletesPctAllowed=9.89560320307762, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 302660 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15a0302c[collection1] main]
[junit4] 2> 302661 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-002/collection1/conf
[junit4] 2> 302662 INFO (coreLoadExecutor-1715-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 302662 INFO (searcherExecutor-1716-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15a0302c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 302664 INFO (qtp547513477-2740) [ 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> 302664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 302664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476996188464
[junit4] 2> 302664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 302664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 302677 INFO (qtp547513477-2741) [ 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=12
[junit4] 2> 302677 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 1
[junit4] 2> 302679 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=43.794921875, floorSegmentMB=0.685546875, forceMergeDeletesPctAllowed=25.30271439469965, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.477252568800434
[junit4] 2> 302680 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 302680 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@7cc7e2ce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20959d65)
[junit4] 2> 302681 INFO (qtp547513477-2734) [ 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> 302682 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=100) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@7cc7e2ce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20959d65)
[junit4] 2> 302683 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=41.0048828125, floorSegmentMB=0.859375, forceMergeDeletesPctAllowed=20.12192402018107, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 302684 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 302684 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@67574a9f[collection1] main]
[junit4] 2> 302684 INFO (searcherExecutor-1716-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@67574a9f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 303137 INFO (qtp1609128861-2754) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 303138 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 303139 INFO (qtp547513477-2736) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 303140 INFO (qtp547513477-2736) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 303149 WARN (qtp547513477-2738) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 303150 INFO (qtp547513477-2738) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 303163 INFO (qtp547513477-2738) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 303165 INFO (qtp547513477-2738) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 303166 INFO (qtp547513477-2738) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/./collection1
[junit4] 2> 303167 INFO (qtp547513477-2738) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/./collection1/data/]
[junit4] 2> 303181 INFO (qtp547513477-2738) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 303181 INFO (qtp547513477-2738) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 303181 INFO (qtp547513477-2738) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a37916e[collection1] main]
[junit4] 2> 303181 INFO (qtp547513477-2738) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-001/collection1/conf
[junit4] 2> 303181 INFO (qtp547513477-2738) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 303181 INFO (qtp547513477-2738) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 303182 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3a37916e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 303182 INFO (qtp547513477-2738) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 303182 INFO (qtp547513477-2738) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 303182 INFO (qtp547513477-2738) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@717d28b6[collection1] main]
[junit4] 2> 303183 INFO (qtp547513477-2738) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4df715ad
[junit4] 2> 303183 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@717d28b6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 303183 INFO (qtp547513477-2738) [ x:collection1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=collection1&qt=/admin/cores&action=reload&wt=javabin&version=2} status=0 QTime=40
[junit4] 2> 303184 INFO (qtp547513477-2740) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 303185 INFO (qtp547513477-2739) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 303187 INFO (qtp547513477-2734) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 303188 INFO (qtp547513477-2737) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 303188 INFO (qtp547513477-2737) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 303188 INFO (qtp547513477-2737) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5489b183
[junit4] 2> 303190 INFO (qtp547513477-2737) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e5f9d6f[collection1] main]
[junit4] 2> 303190 INFO (qtp547513477-2737) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 303190 INFO (searcherExecutor-1722-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e5f9d6f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C2)))}
[junit4] 2> 303190 INFO (qtp547513477-2737) [ 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 2
[junit4] 2> 303192 INFO (qtp547513477-2738) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 303193 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 2 docs
[junit4] 2> 303193 INFO (qtp1609128861-2754) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 303194 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 303305 INFO (qtp1609128861-2754) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 303306 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 303417 INFO (qtp1609128861-2757) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 303417 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 303528 INFO (qtp1609128861-2758) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 303529 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 303637 INFO (qtp1609128861-2758) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 303638 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 303664 INFO (qtp547513477-2738) [ 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> 303664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 303664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476996189065
[junit4] 2> 303664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 303664 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 303668 INFO (qtp547513477-2740) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=3
[junit4] 2> 303668 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 303669 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=23.984375, floorSegmentMB=0.4541015625, forceMergeDeletesPctAllowed=26.685086783633512, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2927060232643116
[junit4] 2> 303669 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 303669 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@30565173 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@daed7a8)
[junit4] 2> 303670 INFO (qtp547513477-2739) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303672 INFO (qtp547513477-2734) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303673 INFO (qtp547513477-2736) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303674 INFO (qtp547513477-2737) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303676 INFO (qtp547513477-2741) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303679 INFO (qtp547513477-2738) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303680 INFO (qtp547513477-2740) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303681 INFO (qtp547513477-2739) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303682 INFO (qtp547513477-2734) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 303683 INFO (qtp547513477-2736) [ 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> 303684 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1713) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@30565173 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@daed7a8)
[junit4] 2> 303685 WARN (indexFetcher-1720-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:689)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265)
[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:1154)
[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> 303685 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10430806784402549]
[junit4] 2> 303686 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 303687 INFO (indexFetcher-1720-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b11f3af[collection1] main]
[junit4] 2> 303687 INFO (searcherExecutor-1716-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4b11f3af[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C2)))}
[junit4] 2> 303742 INFO (qtp1609128861-2752) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 303743 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waited for 500ms and found 2 docs
[junit4] 2> 303743 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 303744 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@7ebf3f4f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 303744 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1588613443
[junit4] 2> 303745 INFO (coreCloseExecutor-1727-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6c0b2286
[junit4] 2> 303748 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@14280ec9{/solr,null,UNAVAILABLE}
[junit4] 2> 303752 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@133be59d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 303753 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1477835002
[junit4] 2> 303753 INFO (coreCloseExecutor-1729-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7ca17013
[junit4] 2> 303756 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4fbc28{/solr,null,UNAVAILABLE}
[junit4] 2> 303759 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexFetchWithMasterUrl
[junit4] 2> 303760 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/collection1
[junit4] 2> 303768 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 303771 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3b7d930e{/solr,null,AVAILABLE}
[junit4] 2> 303771 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78bfafe4{HTTP/1.1,[http/1.1]}{127.0.0.1:52425}
[junit4] 2> 303772 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server Started @311529ms
[junit4] 2> 303772 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=52425}
[junit4] 2> 303772 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 303773 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 303773 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 303773 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 303773 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-20T20:43:09.650Z
[junit4] 2> 303773 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/solr.xml
[junit4] 2> 303810 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/.
[junit4] 2> 303810 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 303817 WARN (coreLoadExecutor-1736-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 303818 INFO (coreLoadExecutor-1736-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 303830 INFO (coreLoadExecutor-1736-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 303831 INFO (coreLoadExecutor-1736-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 303833 INFO (coreLoadExecutor-1736-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/./collection1
[junit4] 2> 303833 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/./collection1/data/]
[junit4] 2> 303834 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 303841 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 303841 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 303841 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=9.5244140625, floorSegmentMB=1.01953125, forceMergeDeletesPctAllowed=9.89560320307762, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 303841 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d7248b9[collection1] main]
[junit4] 2> 303841 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-003/collection1/conf
[junit4] 2> 303842 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 303842 INFO (coreLoadExecutor-1736-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 303842 INFO (searcherExecutor-1737-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d7248b9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 304316 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/collection1
[junit4] 2> 304322 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 304325 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e46df{/solr,null,AVAILABLE}
[junit4] 2> 304325 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Started ServerConnector@342269e2{HTTP/1.1,[http/1.1]}{127.0.0.1:52426}
[junit4] 2> 304325 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server Started @312083ms
[junit4] 2> 304325 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=52426}
[junit4] 2> 304326 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 304326 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 304326 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 304326 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 304326 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-20T20:43:10.203Z
[junit4] 2> 304327 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/solr.xml
[junit4] 2> 304374 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/.
[junit4] 2> 304375 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 304387 WARN (coreLoadExecutor-1746-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 304387 INFO (coreLoadExecutor-1746-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 304396 INFO (coreLoadExecutor-1746-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 304399 INFO (coreLoadExecutor-1746-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 304402 INFO (coreLoadExecutor-1746-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/./collection1
[junit4] 2> 304402 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/./collection1/data/]
[junit4] 2> 304404 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 304414 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 304414 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 304414 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=9.5244140625, floorSegmentMB=1.01953125, forceMergeDeletesPctAllowed=9.89560320307762, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 304414 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5da8b86c[collection1] main]
[junit4] 2> 304414 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/collection1/conf
[junit4] 2> 304417 INFO (coreLoadExecutor-1746-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 304418 INFO (searcherExecutor-1747-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5da8b86c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 304421 INFO (qtp1018886383-2780) [ 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> 304421 INFO (indexFetcher-1751-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 304421 INFO (indexFetcher-1751-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 304421 INFO (indexFetcher-1751-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 304885 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@342269e2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 304886 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1621217866
[junit4] 2> 304886 INFO (coreCloseExecutor-1752-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@97a9784
[junit4] 2> 304889 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2e46df{/solr,null,UNAVAILABLE}
[junit4] 2> 304891 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 304893 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64c25cdc{/solr,null,AVAILABLE}
[junit4] 2> 304893 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1d39cb9b{HTTP/1.1,[http/1.1]}{127.0.0.1:52428}
[junit4] 2> 304893 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.Server Started @312651ms
[junit4] 2> 304893 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=52428}
[junit4] 2> 304894 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 304894 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 304894 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 304894 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 304894 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-20T20:43:10.771Z
[junit4] 2> 304894 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/solr.xml
[junit4] 2> 305133 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/.
[junit4] 2> 305133 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 305140 WARN (coreLoadExecutor-1759-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 305141 INFO (coreLoadExecutor-1759-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 305148 INFO (coreLoadExecutor-1759-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 305150 INFO (coreLoadExecutor-1759-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 305151 INFO (coreLoadExecutor-1759-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/./collection1
[junit4] 2> 305151 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/./collection1/data/]
[junit4] 2> 305153 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 305161 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 305161 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 305161 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=9.5244140625, floorSegmentMB=1.01953125, forceMergeDeletesPctAllowed=9.89560320307762, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 305162 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c445c69[collection1] main]
[junit4] 2> 305162 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001/solr-instance-004/collection1/conf
[junit4] 2> 305162 INFO (coreLoadExecutor-1759-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 305162 INFO (searcherExecutor-1760-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c445c69[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 305647 INFO (qtp1018886383-2784) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 305647 INFO (qtp1018886383-2784) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 305651 INFO (qtp2097170969-2817) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 305651 INFO (qtp2097170969-2817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 305653 INFO (qtp2097170969-2818) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 305653 INFO (qtp2097170969-2818) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4889f047
[junit4] 2> 305655 INFO (qtp2097170969-2818) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7731d1d[collection1] main]
[junit4] 2> 305655 INFO (qtp2097170969-2818) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 305655 INFO (searcherExecutor-1760-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7731d1d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 305656 INFO (qtp2097170969-2818) [ 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 2
[junit4] 2> 305658 INFO (qtp1018886383-2785) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1
[junit4] 2> 305659 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 305660 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 305661 INFO (qtp1018886383-2781) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 305662 INFO (qtp1018886383-2783) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 305664 INFO (qtp1018886383-2782) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 305665 INFO (qtp1018886383-2784) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 305666 INFO (qtp1018886383-2785) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 305666 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 305667 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 305668 INFO (qtp1018886383-2781) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 305669 INFO (qtp1018886383-2783) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 305670 INFO (qtp1018886383-2782) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 305670 INFO (qtp1018886383-2784) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 305671 INFO (qtp1018886383-2785) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 305672 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 305673 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 305681 INFO (qtp1018886383-2781) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 305682 INFO (qtp1018886383-2783) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 305683 INFO (qtp1018886383-2782) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 305684 INFO (qtp1018886383-2784) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 305685 INFO (qtp1018886383-2785) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 305686 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 305687 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 305688 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 305689 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 305690 INFO (qtp1018886383-2782) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 305691 INFO (qtp1018886383-2784) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 305692 INFO (qtp1018886383-2785) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 305693 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 305694 INFO (qtp1018886383-2781) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 305695 INFO (qtp1018886383-2783) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 305696 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 305697 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 305698 INFO (qtp1018886383-2778) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 305700 INFO (qtp1018886383-2785) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 305700 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 305701 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 305702 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 305703 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 305704 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 305705 INFO (qtp1018886383-2780) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[ju
[...truncated too long message...]
ndex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 418530 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 418531 INFO (qtp1382611241-3694) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 418533 INFO (qtp1382611241-3696) [ 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> 418534 INFO (qtp1382611241-3697) [ 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> 418535 INFO (qtp1382611241-3698) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 418536 INFO (qtp1382611241-3699) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 418538 INFO (qtp1382611241-3700) [ 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> 418540 INFO (qtp1382611241-3701) [ 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> 418541 INFO (qtp1382611241-3694) [ 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> 418542 INFO (qtp1382611241-3696) [ 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> 418544 INFO (qtp1382611241-3697) [ 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> 418544 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1804) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@374a190e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@593384e6)
[junit4] 2> 418546 WARN (explicit-fetchindex-cmd) [ 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:689)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 418546 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=41.0048828125, floorSegmentMB=0.859375, forceMergeDeletesPctAllowed=20.12192402018107, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 418547 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 418548 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d6d6bd5[collection1] main]
[junit4] 2> 418548 INFO (searcherExecutor-2310-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d6d6bd5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C3)))}
[junit4] 2> 418549 INFO (qtp1648417719-3679) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52674/solr/collection1/replication&command=fetchindex} status=0 QTime=26
[junit4] 2> 418551 INFO (qtp1648417719-3680) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=6 status=0 QTime=0
[junit4] 2> 418551 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 6 docs
[junit4] 2> 418553 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@15e10741{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 418553 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1542764993
[junit4] 2> 418554 INFO (coreCloseExecutor-2329-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6d9d756
[junit4] 2> 418555 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7949e3f8{/solr,null,UNAVAILABLE}
[junit4] 2> 418558 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestRepeater
[junit4] 2> 418560 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@5f916bac{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 418561 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1750825544
[junit4] 2> 418562 INFO (coreCloseExecutor-2331-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@173b2ee5
[junit4] 2> 418563 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@69fb8d12{/solr,null,UNAVAILABLE}
[junit4] 2> 418566 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@150aebad{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 418566 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2105150626
[junit4] 2> 418567 INFO (coreCloseExecutor-2333-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@654026f5
[junit4] 2> 418569 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[DBF6D87FFD10FC77]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@79e582{/solr,null,UNAVAILABLE}
[junit4] 2> 418572 INFO (SUITE-TestReplicationHandler-seed#[DBF6D87FFD10FC77]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_DBF6D87FFD10FC77-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=false): {}, locale=es-EC, timezone=Cuba
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=92599280,total=286785536
[junit4] 2> NOTE: All tests run in this JVM: [TestImplicitCoreProperties, TestLRUCache, DistributedDebugComponentTest, BadCopyFieldTest, CursorMarkTest, SearchHandlerTest, TestSolrConfigHandlerConcurrent, LeaderInitiatedRecoveryOnCommitTest, TestExpandComponent, TestManagedSchema, DistributedSpellCheckComponentTest, BlockJoinFacetRandomTest, SOLR749Test, TestCSVLoader, OpenExchangeRatesOrgProviderTest, CoreSorterTest, TestMissingGroups, TestMergePolicyConfig, BaseCdcrDistributedZkTest, TestCustomDocTransformer, TermsComponentTest, FileUtilsTest, QueryElevationComponentTest, HdfsBasicDistributedZk2Test, TestFaceting, TestHashPartitioner, ActionThrottleTest, TestUseDocValuesAsStored, SyncSliceTest, MoreLikeThisHandlerTest, TestIBSimilarityFactory, CopyFieldTest, ConnectionManagerTest, PingRequestHandlerTest, SpatialFilterTest, InfoHandlerTest, TestConfigReload, TestTolerantUpdateProcessorRandomCloud, TestCollapseQParserPlugin, TestCSVResponseWriter, HdfsChaosMonkeyNothingIsSafeTest, RestartWhileUpdatingTest, CdcrUpdateLogTest, TestCopyFieldCollectionResource, HdfsDirectoryTest, SampleTest, AlternateDirectoryTest, QueryResultKeyTest, TestHdfsUpdateLog, TestReplicationHandler]
[junit4] Completed [102/640 (1!)] on J0 in 117.63s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 62097 lines...]