You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/01/16 08:14:12 UTC
[JENKINS] Lucene-Solr-6.4-Linux (32bit/jdk1.8.0_112) - Build # 10 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.4-Linux/10/
Java: 32bit/jdk1.8.0_112 -client -XX:+UseG1GC
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.core.MetricsDirectoryFactory.get(MetricsDirectoryFactory.java:201) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:369) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251) 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.core.MetricsDirectoryFactory.get(MetricsDirectoryFactory.java:201)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:369)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
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([5B23FA6202D52758]: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:269)
at sun.reflect.GeneratedMethodAccessor19.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:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
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 11964 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/init-core-data-001
[junit4] 2> 1127592 INFO (SUITE-TestReplicationHandler-seed#[5B23FA6202D52758]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1127594 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 1127594 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1
[junit4] 2> 1127599 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1127601 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1cc2894{/solr,null,AVAILABLE}
[junit4] 2> 1127602 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2de43b{HTTP/1.1,[http/1.1]}{127.0.0.1:43984}
[junit4] 2> 1127603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server Started @1129319ms
[junit4] 2> 1127603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=43984}
[junit4] 2> 1127603 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1127603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 1127603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1127603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1127603 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-16T07:44:41.695Z
[junit4] 2> 1127604 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/solr.xml
[junit4] 2> 1127613 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1127618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/.
[junit4] 2> 1127618 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1127628 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1127639 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1127642 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1127643 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/./collection1
[junit4] 2> 1127644 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1127645 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=82.2548828125, floorSegmentMB=1.5634765625, forceMergeDeletesPctAllowed=19.22650226071619, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1127657 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1127657 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1127658 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=54.9345703125, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=28.212352349012473, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3385534430386723
[junit4] 2> 1127658 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@8400c8[collection1] main]
[junit4] 2> 1127659 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1/conf
[junit4] 2> 1127659 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1127659 INFO (coreLoadExecutor-3207-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1127660 INFO (searcherExecutor-3208-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8400c8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1128116 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/collection1
[junit4] 2> 1128119 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1128120 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a53a1c{/solr,null,AVAILABLE}
[junit4] 2> 1128122 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@110381b{HTTP/1.1,[http/1.1]}{127.0.0.1:44410}
[junit4] 2> 1128122 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server Started @1129838ms
[junit4] 2> 1128122 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=44410}
[junit4] 2> 1128122 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1128122 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 1128122 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1128122 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1128122 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-16T07:44:42.214Z
[junit4] 2> 1128123 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/solr.xml
[junit4] 2> 1128130 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1128134 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/.
[junit4] 2> 1128134 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1128145 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1128156 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1128159 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1128161 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/./collection1
[junit4] 2> 1128161 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1128164 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=82.2548828125, floorSegmentMB=1.5634765625, forceMergeDeletesPctAllowed=19.22650226071619, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1128177 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1128177 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1128177 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=54.9345703125, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=28.212352349012473, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3385534430386723
[junit4] 2> 1128178 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@290775[collection1] main]
[junit4] 2> 1128178 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/collection1/conf
[junit4] 2> 1128180 INFO (coreLoadExecutor-3217-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1128180 INFO (searcherExecutor-3218-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@290775[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1128628 INFO (qtp28082431-6284) [ 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> 1128630 INFO (indexFetcher-3222-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1128630 INFO (indexFetcher-3222-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1128630 INFO (indexFetcher-3222-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1128648 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@110381b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1128649 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=30483026
[junit4] 2> 1128654 INFO (coreCloseExecutor-3223-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e4f8fb
[junit4] 2> 1128655 INFO (coreCloseExecutor-3223-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1128655 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1128656 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a53a1c{/solr,null,UNAVAILABLE}
[junit4] 2> 1128663 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2de43b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1128664 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=23038618
[junit4] 2> 1128665 INFO (coreCloseExecutor-3225-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f70271
[junit4] 2> 1128665 INFO (coreCloseExecutor-3225-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1128665 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1128666 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1cc2894{/solr,null,UNAVAILABLE}
[junit4] 2> 1128667 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1128669 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@677b64{/solr,null,AVAILABLE}
[junit4] 2> 1128670 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@122f28d{HTTP/1.1,[http/1.1]}{127.0.0.1:34496}
[junit4] 2> 1128670 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server Started @1130386ms
[junit4] 2> 1128670 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=34496}
[junit4] 2> 1128670 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1128670 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 1128670 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1128670 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1128670 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-16T07:44:42.762Z
[junit4] 2> 1128671 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/solr.xml
[junit4] 2> 1128676 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1128681 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/.
[junit4] 2> 1128681 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1128691 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1128708 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1128712 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1128713 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/./collection1
[junit4] 2> 1128714 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1128717 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=82.2548828125, floorSegmentMB=1.5634765625, forceMergeDeletesPctAllowed=19.22650226071619, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1128735 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1128735 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1128736 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=54.9345703125, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=28.212352349012473, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3385534430386723
[junit4] 2> 1128737 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1130eee[collection1] main]
[junit4] 2> 1128737 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1/conf
[junit4] 2> 1128737 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 1128737 INFO (coreLoadExecutor-3232-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1128738 INFO (searcherExecutor-3233-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1130eee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1129167 INFO (qtp4791548-6324) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1129167 INFO (qtp4791548-6324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1129170 INFO (qtp4791548-6325) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1129170 INFO (qtp4791548-6325) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dc1b35
[junit4] 2> 1129173 INFO (qtp4791548-6325) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@126fca5[collection1] main]
[junit4] 2> 1129173 INFO (qtp4791548-6325) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1129173 INFO (searcherExecutor-3233-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@126fca5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1129173 INFO (qtp4791548-6325) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1129176 INFO (qtp4791548-6326) [ 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> 1129177 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 1129206 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1129207 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15fa8d{/solr,null,AVAILABLE}
[junit4] 2> 1129209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@df79d2{HTTP/1.1,[http/1.1]}{127.0.0.1:35582}
[junit4] 2> 1129209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server Started @1130925ms
[junit4] 2> 1129209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=35582}
[junit4] 2> 1129209 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1129209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 1129209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1129209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1129209 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-16T07:44:43.301Z
[junit4] 2> 1129210 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/solr.xml
[junit4] 2> 1129220 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1129225 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/.
[junit4] 2> 1129225 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1129236 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1129245 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1129255 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1129257 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/./collection1
[junit4] 2> 1129257 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1129259 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=82.2548828125, floorSegmentMB=1.5634765625, forceMergeDeletesPctAllowed=19.22650226071619, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1129270 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1129271 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1129271 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=54.9345703125, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=28.212352349012473, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3385534430386723
[junit4] 2> 1129272 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@10ece4f[collection1] main]
[junit4] 2> 1129272 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-002/collection1/conf
[junit4] 2> 1129273 INFO (coreLoadExecutor-3242-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1129274 INFO (searcherExecutor-3243-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10ece4f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1129275 INFO (qtp4791548-6324) [ 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> 1129276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1129276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484552683262
[junit4] 2> 1129276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1129276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1129281 INFO (qtp4791548-6325) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=2
[junit4] 2> 1129281 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 1
[junit4] 2> 1129283 INFO (indexFetcher-3247-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.0]
[junit4] 2> 1129285 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1129285 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@13092a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@592364))
[junit4] 2> 1129288 INFO (qtp4791548-6326) [ 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> 1129289 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=100) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@13092a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@592364))
[junit4] 2> 1129291 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=64.087890625, floorSegmentMB=0.5234375, forceMergeDeletesPctAllowed=26.10180864045611, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30801686065928147
[junit4] 2> 1129293 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1129294 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@59ca18[collection1] main]
[junit4] 2> 1129294 INFO (searcherExecutor-3243-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@59ca18[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1129755 INFO (qtp8666172-6342) [ 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> 1129756 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 1129759 INFO (qtp4791548-6319) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1129762 INFO (qtp4791548-6324) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 1129777 INFO (qtp4791548-6321) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1129787 INFO (qtp4791548-6321) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1129789 INFO (qtp4791548-6321) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1129790 INFO (qtp4791548-6321) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/./collection1
[junit4] 2> 1129791 INFO (qtp4791548-6321) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1129805 INFO (qtp4791548-6321) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1129805 INFO (qtp4791548-6321) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1129805 INFO (qtp4791548-6321) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@dfd09f[collection1] main]
[junit4] 2> 1129805 INFO (qtp4791548-6321) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-001/collection1/conf
[junit4] 2> 1129805 INFO (qtp4791548-6321) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 1129806 INFO (qtp4791548-6321) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1129806 INFO (searcherExecutor-3249-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@dfd09f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1129808 INFO (qtp4791548-6321) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1348151834, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1129809 INFO (qtp4791548-6321) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1129809 INFO (qtp4791548-6321) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c6202d[collection1] main]
[junit4] 2> 1129809 INFO (qtp4791548-6321) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1773e82
[junit4] 2> 1129809 INFO (searcherExecutor-3249-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c6202d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1129810 INFO (qtp4791548-6321) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1129810 INFO (qtp4791548-6321) [ x:collection1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=collection1&qt=/admin/cores&action=reload&wt=javabin&version=2} status=0 QTime=43
[junit4] 2> 1129812 INFO (qtp4791548-6325) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1129815 INFO (qtp4791548-6321) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 1129819 INFO (qtp4791548-6319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 1
[junit4] 2> 1129822 INFO (qtp4791548-6324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1129825 INFO (qtp4791548-6325) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1129825 INFO (qtp4791548-6325) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1ac0e6e
[junit4] 2> 1129830 INFO (qtp4791548-6325) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d5912a[collection1] main]
[junit4] 2> 1129830 INFO (qtp4791548-6325) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1129830 INFO (searcherExecutor-3249-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1d5912a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C2)))}
[junit4] 2> 1129831 INFO (qtp4791548-6325) [ 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 5
[junit4] 2> 1129835 INFO (qtp4791548-6321) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 1129836 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 2 docs
[junit4] 2> 1129838 INFO (qtp8666172-6343) [ 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> 1129839 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 1129942 INFO (qtp8666172-6344) [ 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> 1129942 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 1130045 INFO (qtp8666172-6337) [ 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> 1130045 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 1130148 INFO (qtp8666172-6342) [ 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> 1130148 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 1130251 INFO (qtp8666172-6343) [ 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> 1130251 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 1130275 INFO (qtp4791548-6320) [ 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> 1130276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 1130276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484552683917
[junit4] 2> 1130276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1130276 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1130279 INFO (qtp4791548-6324) [ 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=1
[junit4] 2> 1130280 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 1130281 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=29.818359375, floorSegmentMB=1.5751953125, forceMergeDeletesPctAllowed=5.872612293050414, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42642125588207846
[junit4] 2> 1130281 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1130282 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@e38648 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ffff2b))
[junit4] 2> 1130284 INFO (qtp4791548-6325) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130287 INFO (qtp4791548-6321) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130290 INFO (qtp4791548-6320) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130293 INFO (qtp4791548-6324) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130296 INFO (qtp4791548-6325) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130298 INFO (qtp4791548-6321) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130301 INFO (qtp4791548-6320) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130303 INFO (qtp4791548-6324) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130305 INFO (qtp4791548-6325) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1130308 INFO (qtp4791548-6321) [ 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> 1130309 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1663) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@e38648 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ffff2b))
[junit4] 2> 1130310 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1130312 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1130313 INFO (indexFetcher-3247-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@74e6fe[collection1] main]
[junit4] 2> 1130313 INFO (searcherExecutor-3243-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74e6fe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C2)))}
[junit4] 2> 1130355 INFO (qtp8666172-6344) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 1130356 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.h.TestReplicationHandler Waited for 500ms and found 2 docs
[junit4] 2> 1130356 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 1130357 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@122f28d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1130357 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2360345
[junit4] 2> 1130358 INFO (coreCloseExecutor-3254-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@456bcb
[junit4] 2> 1130359 INFO (coreCloseExecutor-3254-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1130359 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1130360 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@677b64{/solr,null,UNAVAILABLE}
[junit4] 2> 1130361 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@df79d2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1130361 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=31555626
[junit4] 2> 1130362 INFO (coreCloseExecutor-3256-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1456bcd
[junit4] 2> 1130363 INFO (coreCloseExecutor-3256-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1130363 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1130364 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@15fa8d{/solr,null,UNAVAILABLE}
[junit4] 2> 1130367 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterWrite2Slave
[junit4] 2> 1130368 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/collection1
[junit4] 2> 1130373 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1130374 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19cbe01{/solr,null,AVAILABLE}
[junit4] 2> 1130375 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16750b3{HTTP/1.1,[http/1.1]}{127.0.0.1:41768}
[junit4] 2> 1130375 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server Started @1132091ms
[junit4] 2> 1130375 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=41768}
[junit4] 2> 1130376 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1130376 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 1130376 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1130376 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1130376 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-16T07:44:44.468Z
[junit4] 2> 1130377 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/solr.xml
[junit4] 2> 1130387 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1130393 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/.
[junit4] 2> 1130393 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1130404 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1130417 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1130421 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1130422 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/./collection1
[junit4] 2> 1130423 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/./collection1/data/]
[junit4] 2> 1130425 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=82.2548828125, floorSegmentMB=1.5634765625, forceMergeDeletesPctAllowed=19.22650226071619, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1130443 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1130443 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1130444 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=54.9345703125, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=28.212352349012473, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3385534430386723
[junit4] 2> 1130445 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bb1c25[collection1] main]
[junit4] 2> 1130446 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-003/collection1/conf
[junit4] 2> 1130446 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1130446 INFO (coreLoadExecutor-3263-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1130446 INFO (searcherExecutor-3264-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@bb1c25[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1131146 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/collection1
[junit4] 2> 1131149 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1131149 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b70fd2{/solr,null,AVAILABLE}
[junit4] 2> 1131150 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1339a29{HTTP/1.1,[http/1.1]}{127.0.0.1:36064}
[junit4] 2> 1131150 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.e.j.s.Server Started @1132866ms
[junit4] 2> 1131150 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=36064}
[junit4] 2> 1131150 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1131150 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.4.0
[junit4] 2> 1131151 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1131151 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1131151 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-16T07:44:45.243Z
[junit4] 2> 1131151 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/solr.xml
[junit4] 2> 1131158 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1131162 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/.
[junit4] 2> 1131163 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1131174 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1131186 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1131189 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1131190 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/./collection1
[junit4] 2> 1131190 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/./collection1/data/]
[junit4] 2> 1131193 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=82.2548828125, floorSegmentMB=1.5634765625, forceMergeDeletesPctAllowed=19.22650226071619, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1131206 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1131206 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1131207 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=54.9345703125, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=28.212352349012473, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3385534430386723
[junit4] 2> 1131208 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ab2ae[collection1] main]
[junit4] 2> 1131208 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-004/collection1/conf
[junit4] 2> 1131209 INFO (coreLoadExecutor-3273-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1131209 INFO (searcherExecutor-3274-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ab2ae[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1131225 INFO (qtp8127433-6369) [ 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> 1131225 INFO (indexFetcher-3278-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1131225 INFO (indexFetcher-3278-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1131225 INFO (indexFetcher-3278-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1131675 INFO (qtp8127433-6365) [ 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> 1131681 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 2
[junit4] 2> 1131684 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1131686 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1131689 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1131693 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1131695 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1131697 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1131702 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 2
[junit4] 2> 1131706 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1131708 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1131711 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1131713 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1131717 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1131720 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1131722 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1131726 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 1
[junit4] 2> 1131729 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1131732 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1131735 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1131738 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1131741 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1131744 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1131747 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1131753 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 4
[junit4] 2> 1131758 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 2
[junit4] 2> 1131762 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1131768 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1131771 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1131773 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1131777 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 1
[junit4] 2> 1131779 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1131784 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 2
[junit4] 2> 1131787 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1131791 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1131794 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1131796 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1131799 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1131803 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1131805 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1131809 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 1
[junit4] 2> 1131813 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1131816 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1131818 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1131821 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1131825 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 1
[junit4] 2> 1131827 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1131830 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1131833 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 1
[junit4] 2> 1131837 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1131840 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1131842 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1131845 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1131849 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1131851 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1131854 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1131858 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 1
[junit4] 2> 1131862 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1131864 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1131867 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1131869 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1131872 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 1
[junit4] 2> 1131875 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1131877 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1131881 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 1
[junit4] 2> 1131885 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 1
[junit4] 2> 1131887 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1131890 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1131892 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1131894 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1131897 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1131899 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1131904 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 2
[junit4] 2> 1131907 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1131910 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1131913 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1131916 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1131918 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1131921 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1131923 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 1131928 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 2
[junit4] 2> 1131931 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 1131934 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 1131936 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 1131939 INFO (qtp8127433-6369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 1131941 INFO (qtp8127433-6364) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 1131944 INFO (qtp8127433-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 1131947 INFO (qtp8127433-6365) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 1131951 INF
[...truncated too long message...]
re/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-030/./collection1/data/]
[junit4] 2> 1237114 INFO (coreLoadExecutor-3859-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=82.2548828125, floorSegmentMB=1.5634765625, forceMergeDeletesPctAllowed=19.22650226071619, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1237131 INFO (coreLoadExecutor-3859-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1237131 INFO (coreLoadExecutor-3859-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1237131 INFO (coreLoadExecutor-3859-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=54.9345703125, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=28.212352349012473, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3385534430386723
[junit4] 2> 1237132 INFO (coreLoadExecutor-3859-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11a1259[collection1] main]
[junit4] 2> 1237133 INFO (coreLoadExecutor-3859-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B23FA6202D52758-001/solr-instance-030/collection1/conf
[junit4] 2> 1237135 INFO (coreLoadExecutor-3859-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1237136 INFO (searcherExecutor-3860-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11a1259[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1237138 INFO (qtp28250337-7318) [ 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> 1237139 INFO (indexFetcher-3864-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1237139 INFO (indexFetcher-3864-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1237139 INFO (indexFetcher-3864-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1237612 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestHandlerPathUnchanged
[junit4] 2> 1237613 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@198fa43{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1237613 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15573326
[junit4] 2> 1237613 INFO (coreCloseExecutor-3865-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@158b1ea
[junit4] 2> 1237614 INFO (coreCloseExecutor-3865-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1237614 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1237614 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@50f055{/solr,null,UNAVAILABLE}
[junit4] 2> 1237618 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@27884f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1237618 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=24756449
[junit4] 2> 1237618 INFO (coreCloseExecutor-3867-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9079de
[junit4] 2> 1237619 INFO (coreCloseExecutor-3867-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1237619 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1237619 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[5B23FA6202D52758]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c66829{/solr,null,UNAVAILABLE}
[junit4] 2> 1237622 INFO (SUITE-TestReplicationHandler-seed#[5B23FA6202D52758]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {name=Lucene50(blocksize=128), id=Lucene50(blocksize=128), newname=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1683, maxMBSortInHeap=6.714783992199788, sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=ar-AE, timezone=Etc/GMT+3
[junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.8.0_112 (32-bit)/cpus=12,threads=1,free=435890976,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestExceedMaxTermLength, CloudMLTQParserTest, BasicZkTest, DistributedSpellCheckComponentTest, SolrXmlInZkTest, RuleEngineTest, TestRangeQuery, ManagedSchemaRoundRobinCloudTest, TestRealTimeGet, DistribDocExpirationUpdateProcessorTest, BlockJoinFacetRandomTest, TestReplicationHandlerBackup, CdcrReplicationDistributedZkTest, AddSchemaFieldsUpdateProcessorFactoryTest, AnalyticsMergeStrategyTest, TestBulkSchemaConcurrent, TestSortByMinMaxFunction, ConjunctionSolrSpellCheckerTest, CreateCollectionCleanupTest, TestHighlightDedupGrouping, TestFastWriter, TestFileDictionaryLookup, TestChildDocTransformer, AlternateDirectoryTest, TestCorePropertiesReload, TestPerFieldSimilarityWithDefaultOverride, NotRequiredUniqueKeyTest, DistributedQueryElevationComponentTest, DistributedVersionInfoTest, RegexBoostProcessorTest, DistanceUnitsTest, SolrCoreTest, TestSchemalessBufferedUpdates, QueryEqualityTest, TestFastLRUCache, ExplicitHLLTest, TestFieldCacheSort, TestRawResponseWriter, TestReRankQParserPlugin, TestFunctionQuery, TestIBSimilarityFactory, TestReplicaProperties, BasicDistributedZkTest, ExternalFileFieldSortTest, TestDynamicFieldResource, TestSearcherReuse, TestCloudSchemaless, CursorPagingTest, ScriptEngineTest, TestRandomFlRTGCloud, TestConfigSetsAPI, HdfsBasicDistributedZkTest, SignatureUpdateProcessorFactoryTest, TestJmxIntegration, StandardRequestHandlerTest, HttpPartitionTest, TestLazyCores, TestNumericTerms64, FastVectorHighlighterTest, TestIntervalFaceting, TestSolrCloudSnapshots, MergeStrategyTest, PreAnalyzedUpdateProcessorTest, DateRangeFieldTest, TestBinaryResponseWriter, TestComplexPhraseLeadingWildcard, ZkStateReaderTest, SynonymTokenizerTest, TestUniqueKeyFieldResource, TestQueryWrapperFilter, TestLeaderElectionWithEmptyReplica, BlockCacheTest, ReturnFieldsTest, ChaosMonkeyNothingIsSafeTest, ReplicaListTransformerTest, TestNRTOpen, TestFieldCacheWithThreads, TestCrossCoreJoin, SolrSlf4jReporterTest, TestDistribIDF, TestOmitPositions, CurrencyFieldOpenExchangeTest, JSONWriterTest, TestSchemaResource, DocumentAnalysisRequestHandlerTest, TestRandomFaceting, PathHierarchyTokenizerFactoryTest, TestSolrCoreProperties, SimpleCollectionCreateDeleteTest, DistributedFacetPivotSmallTest, TestDocumentBuilder, BadCopyFieldTest, HighlighterConfigTest, TestJoin, TestSerializedLuceneMatchVersion, MigrateRouteKeyTest, UUIDFieldTest, TriLevelCompositeIdRoutingTest, DefaultValueUpdateProcessorTest, TestPseudoReturnFields, TestReplicationHandler]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.seed=5B23FA6202D52758 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-AE -Dtests.timezone=Etc/GMT+3 -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.core.MetricsDirectoryFactory.get(MetricsDirectoryFactory.java:201)
[junit4] > at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:369)
[junit4] > at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
[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([5B23FA6202D52758]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:269)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [368/680 (1!)] on J0 in 141.20s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 55683 lines...]