Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18165/ Java: 32bit/jdk1.8.0_102 -client -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication Error Message: expected:<1> but was:<0> Stack Trace: java.lang.AssertionError: expected:<1> but was:<0> at __randomizedtesting.SeedInfo.seed([3C65A73C9568FCBC:CB1649645380535A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1329) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11246 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/init-core-data-001 [junit4] 2> 411104 INFO (SUITE-TestReplicationHandler-seed#[3C65A73C9568FCBC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 411106 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater [junit4] 2> 411107 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/collection1 [junit4] 2> 411112 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 411115 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1435030{/solr,null,AVAILABLE} [junit4] 2> 411117 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@8601c{HTTP/1.1,[http/1.1]}{127.0.0.1:38692} [junit4] 2> 411117 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server Started @412514ms [junit4] 2> 411117 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=38692} [junit4] 2> 411117 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 411117 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 411118 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 411118 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 411119 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-29T02:06:32.762Z [junit4] 2> 411119 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/solr.xml [junit4] 2> 411156 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/. [junit4] 2> 411156 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 411197 WARN (coreLoadExecutor-1189-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 411199 INFO (coreLoadExecutor-1189-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 411216 INFO (coreLoadExecutor-1189-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 411219 INFO (coreLoadExecutor-1189-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 411221 INFO (coreLoadExecutor-1189-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/./collection1 [junit4] 2> 411221 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/./collection1/data/] [junit4] 2> 411223 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=86.62109375, floorSegmentMB=0.69140625, forceMergeDeletesPctAllowed=3.4807046607962198, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3006206968722288 [junit4] 2> 411231 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 411232 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 411232 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=37.1181640625, floorSegmentMB=1.0859375, forceMergeDeletesPctAllowed=9.633720517383463, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 411232 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e24420[collection1] main] [junit4] 2> 411233 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-001/collection1/conf [junit4] 2> 411233 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 411233 INFO (coreLoadExecutor-1189-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 411233 INFO (searcherExecutor-1190-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e24420[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 411661 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/collection1 [junit4] 2> 411664 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 411665 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12bae7c{/solr,null,AVAILABLE} [junit4] 2> 411667 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78af33{HTTP/1.1,[http/1.1]}{127.0.0.1:33905} [junit4] 2> 411667 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server Started @413063ms [junit4] 2> 411667 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=33905} [junit4] 2> 411667 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 411667 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 411667 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 411667 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 411668 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-29T02:06:33.311Z [junit4] 2> 411668 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/solr.xml [junit4] 2> 411678 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/. [junit4] 2> 411678 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 411689 WARN (coreLoadExecutor-1199-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 411691 INFO (coreLoadExecutor-1199-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 411701 INFO (coreLoadExecutor-1199-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 411709 INFO (coreLoadExecutor-1199-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 411710 INFO (coreLoadExecutor-1199-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/./collection1 [junit4] 2> 411711 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/./collection1/data/] [junit4] 2> 411712 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=86.62109375, floorSegmentMB=0.69140625, forceMergeDeletesPctAllowed=3.4807046607962198, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3006206968722288 [junit4] 2> 411720 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 411720 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 411720 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=37.1181640625, floorSegmentMB=1.0859375, forceMergeDeletesPctAllowed=9.633720517383463, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 411720 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1960e26[collection1] main] [junit4] 2> 411720 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/collection1/conf [junit4] 2> 411722 INFO (coreLoadExecutor-1199-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 411722 INFO (searcherExecutor-1200-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1960e26[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 411725 INFO (qtp18373061-2587) [ 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> 411725 INFO (indexFetcher-1204-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 411725 INFO (indexFetcher-1204-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 411725 INFO (indexFetcher-1204-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 412183 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@78af33{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 412183 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=31474314 [junit4] 2> 412184 INFO (coreCloseExecutor-1205-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@32eeb7 [junit4] 2> 412186 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@12bae7c{/solr,null,UNAVAILABLE} [junit4] 2> 412187 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 412188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ca7ccb{/solr,null,AVAILABLE} [junit4] 2> 412189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@142298d{HTTP/1.1,[http/1.1]}{127.0.0.1:33435} [junit4] 2> 412189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server Started @413585ms [junit4] 2> 412189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=33435} [junit4] 2> 412189 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 412189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 412189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 412189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 412190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-29T02:06:33.833Z [junit4] 2> 412190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/solr.xml [junit4] 2> 412203 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/. [junit4] 2> 412204 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 412214 WARN (coreLoadExecutor-1212-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 412215 INFO (coreLoadExecutor-1212-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 412226 INFO (coreLoadExecutor-1212-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 412228 INFO (coreLoadExecutor-1212-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 412229 INFO (coreLoadExecutor-1212-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/./collection1 [junit4] 2> 412229 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/./collection1/data/] [junit4] 2> 412231 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=86.62109375, floorSegmentMB=0.69140625, forceMergeDeletesPctAllowed=3.4807046607962198, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3006206968722288 [junit4] 2> 412238 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 412238 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 412238 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=37.1181640625, floorSegmentMB=1.0859375, forceMergeDeletesPctAllowed=9.633720517383463, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 412239 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c5d6d[collection1] main] [junit4] 2> 412239 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-002/collection1/conf [junit4] 2> 412239 INFO (coreLoadExecutor-1212-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 412239 INFO (searcherExecutor-1213-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c5d6d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 412706 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/collection1 [junit4] 2> 412711 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 412712 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6981e6{/solr,null,AVAILABLE} [junit4] 2> 412713 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1237f54{HTTP/1.1,[http/1.1]}{127.0.0.1:44238} [junit4] 2> 412713 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.Server Started @414109ms [junit4] 2> 412713 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=44238} [junit4] 2> 412713 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 412713 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 412714 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 412714 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 412714 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-29T02:06:34.357Z [junit4] 2> 412714 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/solr.xml [junit4] 2> 412724 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/. [junit4] 2> 412725 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 412743 WARN (coreLoadExecutor-1222-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 412744 INFO (coreLoadExecutor-1222-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 412754 INFO (coreLoadExecutor-1222-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 412757 INFO (coreLoadExecutor-1222-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 412758 INFO (coreLoadExecutor-1222-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/./collection1 [junit4] 2> 412759 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/./collection1/data/] [junit4] 2> 412760 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=86.62109375, floorSegmentMB=0.69140625, forceMergeDeletesPctAllowed=3.4807046607962198, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3006206968722288 [junit4] 2> 412767 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 412767 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 412767 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=37.1181640625, floorSegmentMB=1.0859375, forceMergeDeletesPctAllowed=9.633720517383463, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 412767 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b4a1f6[collection1] main] [junit4] 2> 412767 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-003/collection1/conf [junit4] 2> 412768 WARN (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 412768 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started. [junit4] 2> 412769 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 412769 INFO (coreLoadExecutor-1222-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 412769 INFO (searcherExecutor-1223-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b4a1f6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 413231 INFO (qtp18373061-2582) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 2 [junit4] 2> 413233 INFO (qtp18373061-2587) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 413234 INFO (qtp18373061-2588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 413235 INFO (qtp18373061-2589) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 413235 INFO (qtp18373061-2589) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be4aa [junit4] 2> 413240 INFO (qtp18373061-2589) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1360d31[collection1] main] [junit4] 2> 413240 INFO (qtp18373061-2589) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 413240 INFO (searcherExecutor-1190-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1360d31[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 413240 INFO (qtp18373061-2589) [ 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> 413257 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 413259 INFO (qtp18373061-2587) [ 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> 413260 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 413260 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477706794878 [junit4] 2> 413260 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 413260 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 413263 INFO (qtp18373061-2588) [ 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> 413264 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 413269 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=97.609375, floorSegmentMB=0.5771484375, forceMergeDeletesPctAllowed=3.379030721757792, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14717074996073196 [junit4] 2> 413271 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413271 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1bc3742 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2fddad) [junit4] 2> 413272 INFO (qtp18373061-2589) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413275 INFO (qtp18373061-2584) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413277 INFO (qtp18373061-2587) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413279 INFO (qtp18373061-2588) [ 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> 413280 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2399) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1bc3742 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2fddad) [junit4] 2> 413281 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=25.0771484375, floorSegmentMB=0.896484375, forceMergeDeletesPctAllowed=20.91638481900337, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.540915376341556 [junit4] 2> 413282 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413283 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fcb8b6[collection1] main] [junit4] 2> 413283 INFO (searcherExecutor-1223-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1fcb8b6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 413284 INFO (qtp9722822-2641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:38692/solr/collection1/replication&command=fetchindex} status=0 QTime=28 [junit4] 2> 413292 INFO (qtp9722822-2643) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 413292 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 413295 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 413297 INFO (qtp9722822-2641) [ 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> 413298 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 413298 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477706794878 [junit4] 2> 413298 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 413298 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 413300 INFO (qtp9722822-2637) [ 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=0 [junit4] 2> 413300 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 413302 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=97.609375, floorSegmentMB=0.5771484375, forceMergeDeletesPctAllowed=3.379030721757792, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14717074996073196 [junit4] 2> 413303 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413303 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@463ebd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a024e4) [junit4] 2> 413304 INFO (qtp9722822-2643) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413305 INFO (qtp9722822-2638) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413307 INFO (qtp9722822-2641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413309 INFO (qtp9722822-2637) [ 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> 413309 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2399) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@463ebd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a024e4) [junit4] 2> 413312 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=25.0771484375, floorSegmentMB=0.896484375, forceMergeDeletesPctAllowed=20.91638481900337, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.540915376341556 [junit4] 2> 413313 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413314 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@77d3ac[collection1] main] [junit4] 2> 413314 INFO (searcherExecutor-1213-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@77d3ac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 413314 INFO (qtp19310339-2624) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44238/solr/collection1/replication&command=fetchindex} status=0 QTime=19 [junit4] 2> 413317 INFO (qtp19310339-2626) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 413318 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 413319 INFO (qtp18373061-2582) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 413320 INFO (qtp18373061-2584) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 413323 WARN (qtp9722822-2636) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master [junit4] 2> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:617) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1726) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:938) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:153) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2229) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:80 [/127.0.0.1] failed: Connection refused [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151) [junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:515) [junit4] 2> ... 35 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused [junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method) [junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) [junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) [junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) [junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [junit4] 2> at java.net.Socket.connect(Socket.java:589) [junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134) [junit4] 2> ... 45 more [junit4] 2> 413325 INFO (qtp9722822-2636) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3 [junit4] 2> 413326 INFO (qtp18373061-2587) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 413327 INFO (qtp9722822-2638) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 413329 WARN (qtp9722822-2641) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master [junit4] 2> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:617) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1726) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:938) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:153) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2229) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:80 [/127.0.0.1] failed: Connection refused [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151) [junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:515) [junit4] 2> ... 35 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused [junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method) [junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) [junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) [junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) [junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [junit4] 2> at java.net.Socket.connect(Socket.java:589) [junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134) [junit4] 2> ... 45 more [junit4] 2> 413330 INFO (qtp9722822-2641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 413332 WARN (qtp9722822-2643) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master [junit4] 2> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:617) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1726) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:938) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:153) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2229) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:80 [/127.0.0.1] failed: Connection refused [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151) [junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:515) [junit4] 2> ... 35 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused [junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method) [junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) [junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) [junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) [junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [junit4] 2> at java.net.Socket.connect(Socket.java:589) [junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134) [junit4] 2> ... 45 more [junit4] 2> 413333 INFO (qtp9722822-2643) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 413337 INFO (qtp9722822-2637) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 413338 INFO (qtp19310339-2619) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3 [junit4] 2> 413339 INFO (qtp9722822-2636) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 413339 INFO (qtp19310339-2624) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 413341 INFO (qtp9722822-2641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1 [junit4] 2> 413342 INFO (qtp9722822-2643) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 413343 INFO (qtp9722822-2637) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 413344 INFO (qtp9722822-2636) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0 [junit4] 2> 413345 INFO (qtp9722822-2641) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 413345 INFO (qtp9722822-2641) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1bf9069 [junit4] 2> 413350 INFO (qtp9722822-2641) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1003700[collection1] main] [junit4] 2> 413350 INFO (qtp9722822-2641) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 413350 INFO (searcherExecutor-1223-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1003700[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):c4)))} [junit4] 2> 413350 INFO (qtp9722822-2641) [ 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> 413352 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 413353 INFO (qtp18373061-2589) [ 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> 413353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 413353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477706794878 [junit4] 2> 413353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 413353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 413355 INFO (qtp18373061-2582) [ 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=0 [junit4] 2> 413355 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 413356 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@1bc2ad6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fa8c5f) [junit4] 2> 413356 INFO (qtp18373061-2584) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413358 INFO (qtp18373061-2587) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413360 INFO (qtp18373061-2589) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413373 INFO (qtp18373061-2582) [ 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> 413374 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=2399) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1bc2ad6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fa8c5f) [junit4] 2> 413374 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher New index installed. Updating index properties... index=index.20161028220634998 [junit4] 2> 413375 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics [junit4] 2> java.io.IOException: file "replication.properties" was already written to [junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654) [junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:689) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 413375 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(RAMDirectory@19e6c6f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@159843d) [junit4] 2> 413375 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=97.609375, floorSegmentMB=0.5771484375, forceMergeDeletesPctAllowed=3.379030721757792, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14717074996073196 [junit4] 2> 413376 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413377 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@120413c[collection1] main] [junit4] 2> 413377 INFO (searcherExecutor-1223-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@120413c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 413377 INFO (qtp9722822-2642) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:38692/solr/collection1/replication&command=fetchindex} status=0 QTime=25 [junit4] 2> 413379 INFO (qtp9722822-2643) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 413380 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 413381 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 413383 INFO (qtp9722822-2638) [ 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> 413384 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 413384 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477706794878 [junit4] 2> 413384 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 413384 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 413384 INFO (qtp19310339-2625) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44238/solr/collection1/replication&command=fetchindex} status=0 QTime=2 [junit4] 2> 413385 INFO (qtp19310339-2620) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 413386 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 413387 INFO (qtp18373061-2588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 1 [junit4] 2> 413388 INFO (qtp18373061-2587) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0 [junit4] 2> 413389 INFO (qtp18373061-2589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0 [junit4] 2> 413390 INFO (qtp18373061-2582) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 413390 INFO (qtp18373061-2582) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be4aa [junit4] 2> 413393 INFO (qtp18373061-2582) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bfa2e2[collection1] main] [junit4] 2> 413393 INFO (qtp18373061-2582) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 413393 INFO (searcherExecutor-1190-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@bfa2e2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3) Uninverting(_1(7.0.0):c3)))} [junit4] 2> 413393 INFO (qtp18373061-2582) [ 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> 413395 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 413397 INFO (qtp18373061-2584) [ 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> 413397 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 413397 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477706795033 [junit4] 2> 413397 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 413397 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 413398 INFO (qtp18373061-2587) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 413398 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7 [junit4] 2> 413399 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=97.609375, floorSegmentMB=0.5771484375, forceMergeDeletesPctAllowed=3.379030721757792, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14717074996073196 [junit4] 2> 413400 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413400 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@57a576 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e57d27) [junit4] 2> 413400 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 413400 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 413400 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 413401 INFO (qtp18373061-2589) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413403 INFO (qtp18373061-2582) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413405 INFO (qtp18373061-2584) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413407 INFO (qtp18373061-2587) [ 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> 413408 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2462) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@57a576 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e57d27) [junit4] 2> 413409 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics [junit4] 2> java.io.IOException: file "replication.properties" was already written to [junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654) [junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:689) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 413409 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=25.0771484375, floorSegmentMB=0.896484375, forceMergeDeletesPctAllowed=20.91638481900337, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.540915376341556 [junit4] 2> 413410 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413411 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@57964[collection1] main] [junit4] 2> 413411 INFO (searcherExecutor-1223-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@57964[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3) Uninverting(_1(7.0.0):c3)))} [junit4] 2> 413411 INFO (qtp9722822-2637) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:38692/solr/collection1/replication&command=fetchindex} status=0 QTime=16 [junit4] 2> 413414 INFO (qtp9722822-2636) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=6 status=0 QTime=0 [junit4] 2> 413415 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 6 docs [junit4] 2> 413416 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 413417 INFO (qtp9722822-2641) [ 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> 413418 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 413418 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477706795033 [junit4] 2> 413418 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 413418 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 413420 INFO (qtp9722822-2638) [ 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> 413421 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7 [junit4] 2> 413422 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=97.609375, floorSegmentMB=0.5771484375, forceMergeDeletesPctAllowed=3.379030721757792, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14717074996073196 [junit4] 2> 413423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1ad7819 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75dc9) [junit4] 2> 413423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 413423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 413423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 413425 INFO (qtp9722822-2637) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413426 INFO (qtp9722822-2636) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413428 INFO (qtp9722822-2641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 413429 INFO (qtp9722822-2638) [ 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> 413430 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2462) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1ad7819 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75dc9) [junit4] 2> 413431 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics [junit4] 2> java.io.IOException: file "replication.properties" was already written to [junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654) [junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:689) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 413432 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=25.0771484375, floorSegmentMB=0.896484375, forceMergeDeletesPctAllowed=20.91638481900337, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.540915376341556 [junit4] 2> 413432 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 413433 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@51cb74[collection1] main] [junit4] 2> 413434 INFO (searcherExecutor-1213-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51cb74[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3) Uninverting(_1(7.0.0):c3)))} [junit4] 2> 413434 INFO (qtp19310339-2626) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44238/solr/collection1/replication&command=fetchindex} status=0 QTime=18 [junit4] 2> 413436 INFO (qtp19310339-2619) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=6 status=0 QTime=0 [junit4] 2> 413436 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 6 docs [junit4] 2> 413437 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1237f54{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 413437 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=271 [...truncated too long message...] r 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 519864 INFO (Thread-980) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 519875 INFO (Thread-980) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 519877 INFO (Thread-980) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 519879 INFO (Thread-980) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-030/./collection1 [junit4] 2> 519879 INFO (Thread-980) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-030/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-030/./collection1/data/] [junit4] 2> 519887 INFO (Thread-980) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 519887 INFO (Thread-980) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 519888 INFO (Thread-980) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f381b2[collection1] main] [junit4] 2> 519888 INFO (Thread-980) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001/solr-instance-030/collection1/conf [junit4] 2> 519889 INFO (Thread-980) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 519889 INFO (searcherExecutor-1839-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f381b2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))} [junit4] 2> 519890 INFO (qtp12825642-3581) [ 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> 519890 INFO (Thread-980) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=66.0068359375, floorSegmentMB=1.0615234375, forceMergeDeletesPctAllowed=11.069644427548631, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19010748591354112 [junit4] 2> 519890 INFO (indexFetcher-1843-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 519890 INFO (indexFetcher-1843-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477706901072 [junit4] 2> 519890 INFO (indexFetcher-1843-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 519891 INFO (indexFetcher-1843-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 519891 INFO (Thread-980) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 519891 INFO (Thread-980) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@8f0c3d[collection1] main] [junit4] 2> 519892 INFO (Thread-980) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@d58e42 [junit4] 2> 519892 INFO (searcherExecutor-1839-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8f0c3d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))} [junit4] 2> 519943 INFO (qtp9425569-3600) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 519944 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.a.s.h.TestReplicationHandler Waited for 500ms and found 1 docs [junit4] 2> 519945 INFO (qtp12825642-3576) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 519945 INFO (qtp12825642-3583) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 519948 INFO (qtp12825642-3582) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 519948 INFO (qtp9425569-3601) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 519949 INFO (qtp12825642-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 519949 INFO (qtp9425569-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 519950 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestIndexAndConfigReplication [junit4] 2> 519951 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@139ad0a{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 519951 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=30342575 [junit4] 2> 519951 INFO (coreCloseExecutor-1844-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d544c1 [junit4] 2> 519953 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@14edbe7{/solr,null,UNAVAILABLE} [junit4] 2> 519954 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@17ada33{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 519954 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=7193530 [junit4] 2> 519955 INFO (coreCloseExecutor-1846-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@15136e1 [junit4] 2> 519956 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[3C65A73C9568FCBC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1db6b6d{/solr,null,UNAVAILABLE} [junit4] 2> 519959 INFO (SUITE-TestReplicationHandler-seed#[3C65A73C9568FCBC]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_3C65A73C9568FCBC-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), newname=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{}, maxPointsInLeafNode=644, maxMBSortInHeap=6.8125076679842405, sim=RandomSimilarity(queryNorm=false): {}, locale=en-IN, timezone=America/Tortola [junit4] 2> NOTE: Linux 4.4.0-42-generic i386/Oracle Corporation 1.8.0_102 (32-bit)/cpus=12,threads=1,free=41935064,total=238202880 [junit4] 2> NOTE: All tests run in this JVM: [CollectionTooManyReplicasTest, TestExceedMaxTermLength, TestFunctionQuery, TestRestManager, TestLFUCache, TestCSVLoader, TestLockTree, TestCopyFieldCollectionResource, SignatureUpdateProcessorFactoryTest, SecurityConfHandlerTest, TestNumericTerms32, PluginInfoTest, TestSolr4Spatial2, BasicZkTest, BasicDistributedZkTest, ReturnFieldsTest, ReplicationFactorTest, BasicAuthStandaloneTest, SystemInfoHandlerTest, SmileWriterTest, DistanceUnitsTest, AnalyticsMergeStrategyTest, TestBlendedInfixSuggestions, TestHdfsBackupRestoreCore, TestClusterStateMutator, TestLRUCache, SharedFSAutoReplicaFailoverTest, TestSearcherReuse, DistribDocExpirationUpdateProcessorTest, TestScoreJoinQPScore, ClusterStateTest, DistributedDebugComponentTest, SpellPossibilityIteratorTest, HdfsSyncSliceTest, TestLeaderElectionZkExpiry, TestQueryWrapperFilter, TestSerializedLuceneMatchVersion, CoreAdminRequestStatusTest, TestFoldingMultitermQuery, AnalysisAfterCoreReloadTest, TestJoin, TestSuggestSpellingConverter, DeleteShardTest, MultiThreadedOCPTest, DistributedQueryElevationComponentTest, OverseerStatusTest, TestStressLucene, TestReplicationHandler] [junit4] Completed [191/647 (1!)] on J0 in 108.88s, 15 tests, 1 failure, 1 skipped <<< FAILURES! [...truncated 54176 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org