Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3598/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
at
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
at java.lang.Thread.run(Thread.java:745)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not
released!!! [NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
at
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([976C4812E95EBCC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
at sun.reflect.GeneratedMethodAccessor17.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:834)
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 10810 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_976C4812E95EBCC-001/init-core-data-001
[junit4] 2> 357600 INFO
(SUITE-TestReplicationHandler-seed#[976C4812E95EBCC]-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> 357601 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
[junit4] 2> 357602 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-001/collection1
[junit4] 2> 357610 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 357612 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@45b4fdd7{/solr,null,AVAILABLE}
[junit4] 2> 357621 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@5879c9b6{HTTP/1.1,[http/1.1]}{127.0.0.1:54189}
[junit4] 2> 357621 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.Server Started @362863ms
[junit4] 2> 357621 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-001/collection1/data,
hostContext=/solr, hostPort=54189}
[junit4] 2> 357622 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 357622 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 357622 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 357622 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T05:15:43.745Z
[junit4] 2> 357623 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-001/solr.xml
[junit4] 2> 357651 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-001/.
[junit4] 2> 357651 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 357665 WARN (coreLoadExecutor-1011-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 357666 INFO (coreLoadExecutor-1011-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 357672 INFO (coreLoadExecutor-1011-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 357677 INFO (coreLoadExecutor-1011-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 357680 INFO (coreLoadExecutor-1011-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_976C4812E95EBCC-001/solr-instance-001/./collection1
[junit4] 2> 357681 INFO (coreLoadExecutor-1011-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_976C4812E95EBCC-001/solr-instance-001/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/./collection1/data/]
[junit4] 2> 357681 INFO (coreLoadExecutor-1011-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.11239653555112072]
[junit4] 2> 357691 INFO (coreLoadExecutor-1011-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 357691 INFO (coreLoadExecutor-1011-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 357692 INFO (coreLoadExecutor-1011-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875,
floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346,
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 357692 INFO (coreLoadExecutor-1011-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@71b96c9d[collection1] main]
[junit4] 2> 357692 INFO (coreLoadExecutor-1011-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_976C4812E95EBCC-001/solr-instance-001/collection1/conf
[junit4] 2> 357693 INFO (coreLoadExecutor-1011-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following
config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 357693 INFO (coreLoadExecutor-1011-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 357693 INFO
(searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@71b96c9d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 358166 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-002/collection1
[junit4] 2> 358170 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 358172 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@da9fd28{/solr,null,AVAILABLE}
[junit4] 2> 358173 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@752d2a29{HTTP/1.1,[http/1.1]}{127.0.0.1:54190}
[junit4] 2> 358173 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.Server Started @363415ms
[junit4] 2> 358173 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-002/collection1/data,
hostContext=/solr, hostPort=54190}
[junit4] 2> 358174 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 358174 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 358174 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 358174 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T05:15:44.297Z
[junit4] 2> 358175 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-002/solr.xml
[junit4] 2> 358183 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
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_976C4812E95EBCC-001/solr-instance-002/.
[junit4] 2> 358183 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 358190 WARN (coreLoadExecutor-1021-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 358190 INFO (coreLoadExecutor-1021-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 358196 INFO (coreLoadExecutor-1021-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 358199 INFO (coreLoadExecutor-1021-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 358200 INFO (coreLoadExecutor-1021-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_976C4812E95EBCC-001/solr-instance-002/./collection1
[junit4] 2> 358200 INFO (coreLoadExecutor-1021-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_976C4812E95EBCC-001/solr-instance-002/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/./collection1/data/]
[junit4] 2> 358201 INFO (coreLoadExecutor-1021-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.11239653555112072]
[junit4] 2> 358207 INFO (coreLoadExecutor-1021-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875,
floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346,
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@7e0622f0[collection1] main]
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-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_976C4812E95EBCC-001/solr-instance-002/collection1/conf
[junit4] 2> 358211 INFO (coreLoadExecutor-1021-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of
1000ms
[junit4] 2> 358211 INFO
(searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@7e0622f0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 358697 INFO (qtp1758624125-2266) [ 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> 358699 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 358700 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358700 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 358708 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@30b4d67d[collection1] main]
[junit4] 2> 358708 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358708 INFO
(searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@30b4d67d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 358708 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 358721 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 358724 INFO (qtp1758624125-2270) [ 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> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1476162944823
[junit4] 2> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 358730 INFO (qtp1758624125-2271) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist}
status=0 QTime=5
[junit4] 2> 358730 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 358732 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=612816853,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 358732 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358733 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@32758c62
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62359fd9;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358734 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 358735 INFO (qtp1758624125-2266) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 358737 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 358739 INFO (qtp1758624125-2268) [ 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> 358739 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=1909) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@32758c62
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62359fd9;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358740 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125,
floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897,
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7863546212186346
[junit4] 2> 358741 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358742 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@47457d87[collection1] main]
[junit4] 2> 358742 INFO
(searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@47457d87[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 358742 INFO (qtp546545157-2283) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex}
status=0 QTime=22
[junit4] 2> 358751 INFO (qtp546545157-2285) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 358752 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 358753 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 358754 INFO (qtp1758624125-2270) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 358756 INFO (qtp1758624125-2271) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={slave=false&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 358756 INFO (qtp546545157-2286) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=1
[junit4] 2> 358757 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 358758 INFO (qtp546545157-2287) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 358759 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358759 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 358760 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358760 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 358761 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 358762 INFO (qtp1758624125-2267) [ 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> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1476162944823
[junit4] 2> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 358763 INFO (qtp546545157-2287) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex}
status=0 QTime=2
[junit4] 2> 358764 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 358765 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358765 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 358768 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@64085935[collection1] main]
[junit4] 2> 358768 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358768 INFO
(searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@64085935[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 358768 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 358769 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 358771 INFO (qtp1758624125-2270) [ 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> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1476162944888
[junit4] 2> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 358772 INFO (qtp1758624125-2270) [ 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=0
[junit4] 2> 358773 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 7
[junit4] 2> 358776 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=612816853,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@5a805155
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c41bb8a;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 358777 INFO (qtp1758624125-2266) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 358779 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 358781 INFO (qtp1758624125-2268) [ 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> 358782 INFO (qtp1758624125-2268) [ 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> 358783 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=1972) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@5a805155
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c41bb8a;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358784 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:701)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:513)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[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> 358785 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125,
floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897,
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7863546212186346
[junit4] 2> 358785 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358786 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4ace985e[collection1] main]
[junit4] 2> 358786 INFO
(searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4ace985e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 358786 INFO (qtp546545157-2281) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex}
status=0 QTime=17
[junit4] 2> 358788 INFO (qtp546545157-2283) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 358788 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 358789 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 358790 INFO (qtp1758624125-2271) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 358792 INFO (qtp1758624125-2270) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={slave=false&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 358792 INFO (qtp546545157-2284) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=1
[junit4] 2> 358793 INFO (qtp1758624125-2266) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 358793 INFO (qtp546545157-2285) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 358795 INFO (qtp1758624125-2266) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[3]} 0 1
[junit4] 2> 358796 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358796 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 358799 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@7ee76ded[collection1] realtime]
[junit4] 2> 358799 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358799 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 358800 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 358802 INFO (qtp1758624125-2268) [ 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> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1476162944919
[junit4] 2> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 358803 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist}
status=0 QTime=0
[junit4] 2> 358804 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=612816853,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 359814 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 359814 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 360820 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 360820 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 361821 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 361821 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 362823 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 362823 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 363824 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 363824 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 364834 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 364834 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 365837 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 365837 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 366868 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 366869 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 367871 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 367871 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 368875 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 368875 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index
files to be delete-able
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@dfc0a8e
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1bcfff44;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 369881 INFO (qtp1758624125-2271) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369883 INFO (qtp1758624125-2270) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=_2.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369884 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=_2.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369886 INFO (qtp1758624125-2266) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369886 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=2035) : 11 secs (185 bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@dfc0a8e
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1bcfff44;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369887 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:701)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:513)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[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> 369888 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125,
floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897,
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7863546212186346
[junit4] 2> 369889 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 369891 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@65b6d2c5[collection1] main]
[junit4] 2> 369892 INFO
(searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@65b6d2c5[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 369892 INFO (qtp546545157-2285) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex}
status=0 QTime=11092
[junit4] 2> 369897 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 369897 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 369899 INFO (qtp546545157-2288) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 369900 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 369901 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 369902 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 369902 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 369906 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@769013b0[collection1] main]
[junit4] 2> 369906 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 369906 INFO
(searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@769013b0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)
Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 369906 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 369907 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 369909 INFO (qtp1758624125-2271) [ 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> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 5
[junit4] 2> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1476162956025
[junit4] 2> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 4
[junit4] 2> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 369910 INFO (qtp1758624125-2270) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&wt=javabin&version=2&command=filelist}
status=0 QTime=0
[junit4] 2> 369910 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 13
[junit4] 2> 369913 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=612816853,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4822c3ef
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c6ca0c5;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _2.cfe because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _2.cfs because it already exists
[junit4] 2> 369915 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369916 INFO (qtp1758624125-2266) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_3.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369918 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_3.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369919 INFO (qtp1758624125-2267) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 369919 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=2098) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4822c3ef
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c6ca0c5;
maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369921 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:701)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:513)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[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> 369921 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125,
floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897,
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7863546212186346
[junit4] 2> 369922 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 369924 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@9a4665c[collection1] main]
[junit4] 2> 369925 INFO
(searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@9a4665c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)
Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 369925 INFO (qtp546545157-2287) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex}
status=0 QTime=18
[junit4] 2> 369927 INFO (qtp1758624125-2271) [ 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> 369927 INFO (qtp1758624125-2268) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2}
hits=4 status=0 QTime=0
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 5
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1476162956025
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 5
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1]
o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 369927 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 369928 INFO (qtp546545157-2281) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2}
hits=4 status=0 QTime=0
[junit4] 2> 369929 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 369929 INFO (qtp1758624125-2271) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 369930 INFO (qtp1758624125-2264) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 369931 INFO (qtp1758624125-2266) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={slave=false&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 369932 INFO (qtp546545157-2283) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=1
[junit4] 2> 369933 INFO (qtp1758624125-2269) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 369933 INFO (qtp546545157-2284) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 369934 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
[junit4] 2> 369935 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.ServerConnector Stopped
ServerConnector@5879c9b6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 369935 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1012943509
[junit4] 2> 369936 INFO (coreCloseExecutor-1031-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@59749d91
[junit4] 2> 369938 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@45b4fdd7{/solr,null,UNAVAILABLE}
[junit4] 2> 369940 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.ServerConnector Stopped
ServerConnector@752d2a29{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 369940 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1904598744
[junit4] 2> 369941 INFO (coreCloseExecutor-1033-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4b3ba175
[junit4] 2> 369944 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@da9fd28{/solr,null,UNAVAILABLE}
[junit4] 2> 369947 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.SolrTestCaseJ4 ###Starting doTestHandlerPathUnchanged
[junit4] 2> 369947 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-003/collection1
[junit4] 2> 369952 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 369956 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@30539e2c{/solr,null,AVAILABLE}
[junit4] 2> 369957 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@4ef7235d{HTTP/1.1,[http/1.1]}{127.0.0.1:54223}
[junit4] 2> 369957 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server Started @375199ms
[junit4] 2> 369957 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-003/collection1/data,
hostContext=/solr, hostPort=54223}
[junit4] 2> 369958 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 7.0.0
[junit4] 2> 369958 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode
on port null
[junit4] 2> 369958 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 369958 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T05:15:56.081Z
[junit4] 2> 369958 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-003/solr.xml
[junit4] 2> 369970 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-003/.
[junit4] 2> 369970 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 369978 WARN (coreLoadExecutor-1040-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 369979 INFO (coreLoadExecutor-1040-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 369993 INFO (coreLoadExecutor-1040-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 369994 INFO (coreLoadExecutor-1040-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 369995 INFO (coreLoadExecutor-1040-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_976C4812E95EBCC-001/solr-instance-003/./collection1
[junit4] 2> 369995 INFO (coreLoadExecutor-1040-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_976C4812E95EBCC-001/solr-instance-003/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/./collection1/data/]
[junit4] 2> 369996 INFO (coreLoadExecutor-1040-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.11239653555112072]
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875,
floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346,
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@4142d3ad[collection1] main]
[junit4] 2> 370005 INFO (coreLoadExecutor-1040-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_976C4812E95EBCC-001/solr-instance-003/collection1/conf
[junit4] 2> 370005 INFO (coreLoadExecutor-1040-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following
config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 370005 INFO (coreLoadExecutor-1040-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 370005 INFO
(searcherExecutor-1041-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4142d3ad[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 370483 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-004/collection1
[junit4] 2> 370488 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 370491 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7aa5eb1{/solr,null,AVAILABLE}
[junit4] 2> 370491 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@23c088b1{HTTP/1.1,[http/1.1]}{127.0.0.1:54224}
[junit4] 2> 370491 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server Started @375733ms
[junit4] 2> 370491 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-004/collection1/data,
hostContext=/solr, hostPort=54224}
[junit4] 2> 370492 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 7.0.0
[junit4] 2> 370492 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode
on port null
[junit4] 2> 370492 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 370492 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T05:15:56.615Z
[junit4] 2> 370492 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-004/solr.xml
[junit4] 2> 370517 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-004/.
[junit4] 2> 370517 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 370531 WARN (coreLoadExecutor-1050-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 370531 INFO (coreLoadExecutor-1050-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 370537 INFO (coreLoadExecutor-1050-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 370540 INFO (coreLoadExecutor-1050-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 370543 INFO (coreLoadExecutor-1050-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_976C4812E95EBCC-001/solr-instance-004/./collection1
[junit4] 2> 370543 INFO (coreLoadExecutor-1050-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_976C4812E95EBCC-001/solr-instance-004/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/./collection1/data/]
[junit4] 2> 370544 INFO (coreLoadExecutor-1050-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.11239653555112072]
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875,
floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346,
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@799ff9e3[collection1] main]
[junit4] 2> 370555 INFO (coreLoadExecutor-1050-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_976C4812E95EBCC-001/solr-instance-004/collection1/conf
[junit4] 2> 370556 INFO (coreLoadExecutor-1050-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of
1000ms
[junit4] 2> 370556 INFO
(searcherExecutor-1051-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@799ff9e3[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 371021 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.SolrTestCaseJ4 ###Ending doTestHandlerPathUnchanged
[junit4] 2> 371022 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Stopped
ServerConnector@4ef7235d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 371022 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1368414730
[junit4] 2> 371023 INFO (coreCloseExecutor-1056-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@2832e623
[junit4] 2> 371025 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@30539e2c{/solr,null,UNAVAILABLE}
[junit4] 2> 371027 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Stopped
ServerConnector@23c088b1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 371027 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1714044272
[junit4] 2> 371028 INFO (coreCloseExecutor-1058-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@6ef141a5
[junit4] 2> 371030 INFO
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7aa5eb1{/solr,null,UNAVAILABLE}
[junit4] 2> 371033 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 371034 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-005/collection1
[junit4] 2> 371039 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 371041 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@79a1ad45{/solr,null,AVAILABLE}
[junit4] 2> 371041 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@6d3d72f7{HTTP/1.1,[http/1.1]}{127.0.0.1:54225}
[junit4] 2> 371041 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server Started @376283ms
[junit4] 2> 371041 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-005/collection1/data,
hostContext=/solr, hostPort=54225}
[junit4] 2> 371042 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 7.0.0
[junit4] 2> 371042 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone
mode on port null
[junit4] 2> 371042 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 371042 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T05:15:57.165Z
[junit4] 2> 371042 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-005/solr.xml
[junit4] 2> 371051 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-005/.
[junit4] 2> 371051 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 371058 WARN (coreLoadExecutor-1065-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 371059 INFO (coreLoadExecutor-1065-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 371066 INFO (coreLoadExecutor-1065-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 371067 INFO (coreLoadExecutor-1065-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 371068 INFO (coreLoadExecutor-1065-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_976C4812E95EBCC-001/solr-instance-005/./collection1
[junit4] 2> 371069 INFO (coreLoadExecutor-1065-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_976C4812E95EBCC-001/solr-instance-005/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/./collection1/data/]
[junit4] 2> 371070 INFO (coreLoadExecutor-1065-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.11239653555112072]
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875,
floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346,
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a9f2da4[collection1]
main]
[junit4] 2> 371079 INFO (coreLoadExecutor-1065-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_976C4812E95EBCC-001/solr-instance-005/collection1/conf
[junit4] 2> 371079 INFO (coreLoadExecutor-1065-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following
config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 371079 INFO (coreLoadExecutor-1065-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 371079 INFO
(searcherExecutor-1066-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@a9f2da4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 371559 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-006/collection1
[junit4] 2> 371564 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 371566 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@47db7f01{/solr,null,AVAILABLE}
[junit4] 2> 371566 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@56238302{HTTP/1.1,[http/1.1]}{127.0.0.1:54226}
[junit4] 2> 371566 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.e.j.s.Server Started @376808ms
[junit4] 2> 371566 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-006/collection1/data,
hostContext=/solr, hostPort=54226}
[junit4] 2> 371567 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 7.0.0
[junit4] 2> 371567 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone
mode on port null
[junit4] 2> 371567 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 371567 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T05:15:57.690Z
[junit4] 2> 371567 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-006/solr.xml
[junit4] 2> 371589 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] 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_976C4812E95EBCC-001/solr-instance-006/.
[junit4] 2> 371589 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 371596 WARN (coreLoadExecutor-1075-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 371597 INFO (coreLoadExecutor-1075-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 371604 INFO (coreLoadExecutor-1075-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 371605 INFO (coreLoadExecutor-1075-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 371606 INFO (coreLoadExecutor-1075-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_976C4812E95EBCC-001/solr-instance-006/./collection1
[junit4] 2> 371607 INFO (coreLoadExecutor-1075-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_976C4812E95EBCC-001/solr-instance-006/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/./collection1/data/]
[junit4] 2> 371608 INFO (coreLoadExecutor-1075-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.11239653555112072]
[junit4] 2> 371616 INFO (coreLoadExecutor-1075-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 371616 INFO (coreLoadExecutor-1075-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 371616 INFO (coreLoadExecutor-1075-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePo
[...truncated too long message...]
1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5387a358[collection1] main]
[junit4] 2> 423439 INFO (qtp1069260691-3273) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 423440 INFO
(searcherExecutor-1656-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5387a358[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 423440 INFO (qtp1069260691-3273) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 1
[junit4] 2> 423440 INFO (qtp1069260691-3272) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[554]} 0 0
[junit4] 2> 423441 INFO (qtp1069260691-3274) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 423441 INFO (qtp1069260691-3274) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@23cc5c36
[junit4] 2> 423443 INFO (qtp1069260691-3274) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@68ac1bab[collection1] main]
[junit4] 2> 423443 INFO (qtp1069260691-3274) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 423443 INFO
(searcherExecutor-1656-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@68ac1bab[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)
Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 423443 INFO (qtp1069260691-3274) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 1
[junit4] 2> 423444 INFO (qtp1069260691-3275) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[555]} 0 0
[junit4] 2> 423444 INFO (qtp1069260691-3276) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 423444 INFO (qtp1069260691-3276) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@23cc5c36
[junit4] 2> 423446 INFO (qtp1069260691-3276) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a8f0910[collection1] main]
[junit4] 2> 423446 INFO (qtp1069260691-3276) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 423446 INFO
(searcherExecutor-1656-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1a8f0910[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1) Uninverting(_3(7.0.0):c1)
Uninverting(_4(7.0.0):c1)))}
[junit4] 2> 423446 INFO (qtp1069260691-3276) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 1
[junit4] 2> 423447 INFO (qtp1069260691-3277) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=id:555&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 423447 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 423448 INFO (qtp1387224089-3260) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={command=enablereplication} status=0 QTime=0
[junit4] 2> 423449 INFO (qtp1069260691-3270) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={command=fetchindex} status=0 QTime=0
[junit4] 2> 423450 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.a.s.SolrTestCaseJ4 ###Ending doTestReplicateAfterWrite2Slave
[junit4] 2> 423452 ERROR (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master at: http://127.0.0.1:54402/solr/collection1 is not
available. Index fetch failed. Exception: Error from server at
http://127.0.0.1:54402/solr/collection1: Expected mime type
application/octet-stream but got text/html. <html>
[junit4] 2> <head>
[junit4] 2> <meta http-equiv="Content-Type"
content="text/html;charset=ISO-8859-1"/>
[junit4] 2> <title>Error 503 </title>
[junit4] 2> </head>
[junit4] 2> <body>
[junit4] 2> <h2>HTTP ERROR: 503</h2>
[junit4] 2> <p>Problem accessing /solr/collection1/replication. Reason:
[junit4] 2> <pre> Service Unavailable</pre></p>
[junit4] 2> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty://
9.3.8.v20160314</a><hr/>
[junit4] 2> </body>
[junit4] 2> </html>
[junit4] 2>
[junit4] 2> 423453 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Stopped
ServerConnector@314da7b1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 423453 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1145242557
[junit4] 2> 423454 INFO (coreCloseExecutor-1661-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@32a32ba0
[junit4] 2> 423456 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@153a28e5{/solr,null,UNAVAILABLE}
[junit4] 2> 423458 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.e.j.s.ServerConnector Stopped
ServerConnector@16d20e9c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 423458 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=253486905
[junit4] 2> 423459 INFO (coreCloseExecutor-1663-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@2da2535b
[junit4] 2> 423460 INFO
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@35d979a2{/solr,null,UNAVAILABLE}
[junit4] 2> 423462 INFO
(SUITE-TestReplicationHandler-seed#[976C4812E95EBCC]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{name=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting),
newname=PostingsFormat(name=Asserting)}, docValues:{}, maxPointsInLeafNode=788,
maxMBSortInHeap=7.097413682666291, sim=ClassicSimilarity, locale=und,
timezone=America/Indiana/Indianapolis
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102
(64-bit)/cpus=3,threads=1,free=165528136,total=286785536
[junit4] 2> NOTE: All tests run in this JVM: [TestSort,
DistributedDebugComponentTest, TestSolr4Spatial2, TestSchemaNameResource,
TestSolrDeletionPolicy1, TriLevelCompositeIdRoutingTest,
TestManagedStopFilterFactory, TestSimpleQParserPlugin, TestJsonRequest,
IndexSchemaRuntimeFieldTest, TestRandomCollapseQParserPlugin,
MigrateRouteKeyTest, CollectionsAPIAsyncDistributedZkTest,
TestDownShardTolerantSearch, TestSubQueryTransformerDistrib,
TestCloudPivotFacet, SegmentsInfoRequestHandlerTest, TestStandardQParsers,
TestHighlightDedupGrouping, TestExactSharedStatsCache,
TestRequestStatusCollectionAPI, DirectUpdateHandlerTest, TestLockTree,
TestMergePolicyConfig, TestExceedMaxTermLength, RecoveryZkTest,
TestReplicationHandler]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestReplicationHandler -Dtests.seed=976C4812E95EBCC
-Dtests.slow=true -Dtests.locale=und
-Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestReplicationHandler (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1
object(s) that were not released!!! [NRTCachingDirectory]
[junit4] >
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
[junit4] > at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] > at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] > at
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at
__randomizedtesting.SeedInfo.seed([976C4812E95EBCC]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [81/635 (1!)] on J0 in 97.11s, 15 tests, 1 failure, 1
skipped <<< FAILURES!
[...truncated 62121 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
