Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3692/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting Error Message: Should have exactly 4 documents returned expected:<4> but was:<3> Stack Trace: java.lang.AssertionError: Should have exactly 4 documents returned expected:<4> but was:<3> at __randomizedtesting.SeedInfo.seed([83573CA4F2A6D81B:9D6F34AC8E0D629B]: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.apache.solr.cloud.DocValuesNotIndexedTest.checkSortOrder(DocValuesNotIndexedTest.java:254) at org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting(DocValuesNotIndexedTest.java:239) 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) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication Error Message: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data/index.20161205151852185, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data/snapshot_metadata, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data/index.20161205151852326] expected:<3> but was:<4> Stack Trace: java.lang.AssertionError: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data/index.20161205151852185, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data/snapshot_metadata, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-012/./collection1/data/index.20161205151852326] expected:<3> but was:<4> at __randomizedtesting.SeedInfo.seed([83573CA4F2A6D81B:7424D2FC344E77FD]: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.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:902) at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1334) 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 11682 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/init-core-data-001 [junit4] 2> 1877223 INFO (SUITE-TestReplicationHandler-seed#[83573CA4F2A6D81B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) w/ MAC_OS_X supressed clientAuth [junit4] 2> 1877225 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater [junit4] 2> 1877226 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/collection1 [junit4] 2> 1877277 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1877279 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@609fbe1d{/solr,null,AVAILABLE} [junit4] 2> 1877282 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1bc01304{HTTP/1.1,[http/1.1]}{127.0.0.1:58552} [junit4] 2> 1877282 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server Started @1882454ms [junit4] 2> 1877282 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=58552} [junit4] 2> 1877282 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1877283 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1877283 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1877283 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1877283 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-05T19:18:38.260Z [junit4] 2> 1877284 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/solr.xml [junit4] 2> 1877300 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/. [junit4] 2> 1877301 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1877317 INFO (coreLoadExecutor-5998-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 1877337 INFO (coreLoadExecutor-5998-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1877342 INFO (coreLoadExecutor-5998-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 1877347 INFO (coreLoadExecutor-5998-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/./collection1 [junit4] 2> 1877347 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/./collection1/data/] [junit4] 2> 1877348 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=65.412109375, floorSegmentMB=0.3251953125, forceMergeDeletesPctAllowed=2.2654277428071676, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1877359 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1877359 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1877359 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4589483969254766] [junit4] 2> 1877359 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4317dca4[collection1] main] [junit4] 2> 1877359 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-001/collection1/conf [junit4] 2> 1877360 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 1877360 INFO (coreLoadExecutor-5998-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1877361 INFO (searcherExecutor-5999-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4317dca4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1877809 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/collection1 [junit4] 2> 1877820 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1877823 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@587d7d6e{/solr,null,AVAILABLE} [junit4] 2> 1877824 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@26acaed{HTTP/1.1,[http/1.1]}{127.0.0.1:58555} [junit4] 2> 1877824 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server Started @1882995ms [junit4] 2> 1877824 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=58555} [junit4] 2> 1877824 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1877824 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1877825 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1877825 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1877825 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-05T19:18:38.802Z [junit4] 2> 1877825 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/solr.xml [junit4] 2> 1877843 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/. [junit4] 2> 1877843 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1877852 INFO (coreLoadExecutor-6008-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 1877862 INFO (coreLoadExecutor-6008-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1877864 INFO (coreLoadExecutor-6008-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 1877866 INFO (coreLoadExecutor-6008-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/./collection1 [junit4] 2> 1877866 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/./collection1/data/] [junit4] 2> 1877867 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=65.412109375, floorSegmentMB=0.3251953125, forceMergeDeletesPctAllowed=2.2654277428071676, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1877880 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1877880 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1877880 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4589483969254766] [junit4] 2> 1877880 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7615cc9a[collection1] main] [junit4] 2> 1877881 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/collection1/conf [junit4] 2> 1877883 INFO (coreLoadExecutor-6008-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 1877884 INFO (searcherExecutor-6009-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7615cc9a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1878048 INFO (qtp2143272057-13007) [ 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> 1878049 INFO (indexFetcher-6013-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 1878049 INFO (indexFetcher-6013-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 1878049 INFO (indexFetcher-6013-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1878353 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@26acaed{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 1878353 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1107895545 [junit4] 2> 1878354 INFO (coreCloseExecutor-6014-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5430fa2e [junit4] 2> 1878356 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@587d7d6e{/solr,null,UNAVAILABLE} [junit4] 2> 1878358 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1878360 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1208385f{/solr,null,AVAILABLE} [junit4] 2> 1878361 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@77bbca64{HTTP/1.1,[http/1.1]}{127.0.0.1:58557} [junit4] 2> 1878361 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server Started @1883533ms [junit4] 2> 1878361 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=58557} [junit4] 2> 1878361 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1878361 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1878362 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1878362 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1878362 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-05T19:18:39.339Z [junit4] 2> 1878362 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/solr.xml [junit4] 2> 1878370 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/. [junit4] 2> 1878370 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1878377 INFO (coreLoadExecutor-6021-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 1878383 INFO (coreLoadExecutor-6021-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1878385 INFO (coreLoadExecutor-6021-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 1878386 INFO (coreLoadExecutor-6021-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/./collection1 [junit4] 2> 1878386 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/./collection1/data/] [junit4] 2> 1878387 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=65.412109375, floorSegmentMB=0.3251953125, forceMergeDeletesPctAllowed=2.2654277428071676, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1878395 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1878395 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1878395 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4589483969254766] [junit4] 2> 1878395 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34242853[collection1] main] [junit4] 2> 1878395 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-002/collection1/conf [junit4] 2> 1878396 INFO (coreLoadExecutor-6021-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1878396 INFO (searcherExecutor-6022-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34242853[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1878875 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/collection1 [junit4] 2> 1878894 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1878896 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34ced15d{/solr,null,AVAILABLE} [junit4] 2> 1878896 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@24d1ad7b{HTTP/1.1,[http/1.1]}{127.0.0.1:58558} [junit4] 2> 1878896 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.e.j.s.Server Started @1884068ms [junit4] 2> 1878896 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=58558} [junit4] 2> 1878897 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1878897 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1878897 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1878897 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1878897 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-12-05T19:18:39.874Z [junit4] 2> 1878898 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/solr.xml [junit4] 2> 1878912 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/. [junit4] 2> 1878913 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1878921 INFO (coreLoadExecutor-6031-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 1878926 INFO (coreLoadExecutor-6031-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1878930 INFO (coreLoadExecutor-6031-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 1878931 INFO (coreLoadExecutor-6031-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/./collection1 [junit4] 2> 1878931 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/./collection1/data/] [junit4] 2> 1878932 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=65.412109375, floorSegmentMB=0.3251953125, forceMergeDeletesPctAllowed=2.2654277428071676, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1878939 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1878940 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1878940 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4589483969254766] [junit4] 2> 1878940 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d3597f9[collection1] main] [junit4] 2> 1878940 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_83573CA4F2A6D81B-001/solr-instance-003/collection1/conf [junit4] 2> 1878941 WARN (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1878942 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started. [junit4] 2> 1878942 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 1878942 INFO (coreLoadExecutor-6031-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1878942 INFO (searcherExecutor-6032-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1d3597f9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1879428 INFO (qtp2143272057-13008) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1 [junit4] 2> 1879429 INFO (qtp2143272057-13011) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 1879430 INFO (qtp2143272057-13012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 1879430 INFO (qtp2143272057-13005) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1879431 INFO (qtp2143272057-13005) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@42b99dc8 [junit4] 2> 1879434 INFO (qtp2143272057-13005) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b8ce209[collection1] main] [junit4] 2> 1879434 INFO (qtp2143272057-13005) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1879435 INFO (searcherExecutor-5999-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b8ce209[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))} [junit4] 2> 1879435 INFO (qtp2143272057-13005) [ 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 4 [junit4] 2> 1879441 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1879444 INFO (qtp2143272057-13007) [ 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> 1879444 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 1879445 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1480965520408 [junit4] 2> 1879445 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1879445 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1879452 INFO (qtp2143272057-13009) [ 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=6 [junit4] 2> 1879452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 1879453 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7567073224138933] [junit4] 2> 1879454 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1879454 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@53fc0b2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f659da3) [junit4] 2> 1879456 INFO (qtp2143272057-13010) [ 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> 1879458 INFO (qtp2143272057-13008) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879460 INFO (qtp2143272057-13011) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879462 INFO (qtp2143272057-13012) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879463 INFO (qtp2143272057-13005) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879464 INFO (qtp2143272057-13007) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879473 INFO (qtp2143272057-13009) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879474 INFO (qtp2143272057-13010) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879476 INFO (qtp2143272057-13008) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879477 INFO (qtp2143272057-13011) [ 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> 1879478 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1730) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@53fc0b2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f659da3) [junit4] 2> 1879480 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=7.55078125, floorSegmentMB=1.12890625, forceMergeDeletesPctAllowed=23.522105364224338, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1196190630345848 [junit4] 2> 1879481 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1879482 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@73de52c2[collection1] main] [junit4] 2> 1879482 INFO (searcherExecutor-6032-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@73de52c2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))} [junit4] 2> 1879482 INFO (qtp1191638316-13061) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:58552/solr/collection1/replication&command=fetchindex} status=0 QTime=42 [junit4] 2> 1879488 INFO (qtp1191638316-13062) [ 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=2 [junit4] 2> 1879488 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 1879493 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1879495 INFO (qtp1191638316-13064) [ 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> 1879496 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 1879496 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1480965520408 [junit4] 2> 1879496 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1879496 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1879497 INFO (qtp1191638316-13065) [ 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=1 [junit4] 2> 1879498 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 1879498 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7567073224138933] [junit4] 2> 1879499 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1879499 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1a0e99e4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31b68854) [junit4] 2> 1879500 INFO (qtp1191638316-13066) [ 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> 1879502 INFO (qtp1191638316-13059) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879505 INFO (qtp1191638316-13061) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879506 INFO (qtp1191638316-13062) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879507 INFO (qtp1191638316-13063) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879511 INFO (qtp1191638316-13064) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879512 INFO (qtp1191638316-13065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879513 INFO (qtp1191638316-13066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879514 INFO (qtp1191638316-13059) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879516 INFO (qtp1191638316-13061) [ 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> 1879517 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1730) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1a0e99e4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31b68854) [junit4] 2> 1879518 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=7.55078125, floorSegmentMB=1.12890625, forceMergeDeletesPctAllowed=23.522105364224338, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1196190630345848 [junit4] 2> 1879519 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1879520 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@46d07e92[collection1] main] [junit4] 2> 1879521 INFO (searcherExecutor-6022-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@46d07e92[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))} [junit4] 2> 1879521 INFO (qtp640190682-13044) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:58558/solr/collection1/replication&command=fetchindex} status=0 QTime=28 [junit4] 2> 1879523 INFO (qtp640190682-13045) [ 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> 1879524 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 1879525 INFO (qtp2143272057-13012) [ 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> 1879526 INFO (qtp2143272057-13005) [ 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> 1879531 WARN (qtp1191638316-13062) [ 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:152) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2228) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464) [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:1699) [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:1699) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [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:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [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:1112) [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:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [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.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [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> ... 36 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> ... 46 more [junit4] 2> 1879533 INFO (qtp1191638316-13062) [ 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=4 [junit4] 2> 1879534 INFO (qtp2143272057-13007) [ 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> 1879535 INFO (qtp1191638316-13063) [ 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> 1879537 WARN (qtp1191638316-13064) [ 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:152) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2228) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464) [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:1699) [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:1699) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [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:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [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:1112) [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:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [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.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [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> ... 36 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> ... 46 more [junit4] 2> 1879539 INFO (qtp1191638316-13064) [ 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> 1879542 WARN (qtp1191638316-13065) [ 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:152) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2228) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464) [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:1699) [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:1699) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [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:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [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:1112) [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:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [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.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [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> ... 36 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> ... 46 more [junit4] 2> 1879543 INFO (qtp1191638316-13065) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1 [junit4] 2> 1879545 INFO (qtp1191638316-13066) [ 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> 1879546 INFO (qtp640190682-13046) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1 [junit4] 2> 1879547 INFO (qtp1191638316-13059) [ 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> 1879548 INFO (qtp640190682-13047) [ 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> 1879549 INFO (qtp1191638316-13061) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1 [junit4] 2> 1879550 INFO (qtp1191638316-13062) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 1879551 INFO (qtp1191638316-13063) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 1879553 INFO (qtp1191638316-13064) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0 [junit4] 2> 1879553 INFO (qtp1191638316-13065) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1879553 INFO (qtp1191638316-13065) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@80fb6c [junit4] 2> 1879555 INFO (qtp1191638316-13065) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e97e81c[collection1] main] [junit4] 2> 1879555 INFO (qtp1191638316-13065) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1879555 INFO (searcherExecutor-6032-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e97e81c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C4)))} [junit4] 2> 1879555 INFO (qtp1191638316-13065) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 1879557 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1879558 INFO (qtp2143272057-13007) [ 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> 1879558 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 1879558 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1480965520408 [junit4] 2> 1879559 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 1879559 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1879559 INFO (qtp2143272057-13010) [ 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> 1879560 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 1879560 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@6432fd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e6d1e1a) [junit4] 2> 1879561 INFO (qtp2143272057-13008) [ 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> 1879562 INFO (qtp2143272057-13008) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879564 INFO (qtp2143272057-13012) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879566 INFO (qtp2143272057-13012) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879567 INFO (qtp2143272057-13012) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879568 INFO (qtp2143272057-13007) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879570 INFO (qtp2143272057-13010) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879571 INFO (qtp2143272057-13011) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879573 INFO (qtp2143272057-13008) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1879574 INFO (qtp2143272057-13005) [ 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> 1879574 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=1730) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@6432fd4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e6d1e1a) [junit4] 2> 1879575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher New index installed. Updating index properties... index=index.20161205151840537 [junit4] 2> 1879575 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> 1879575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(RAMDirectory@1432e4e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@71a0043c) [junit4] 2> 1879575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7567073224138933] [junit4] 2> 1879575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1879575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a857293[collection1] main] [junit4] 2> 1879575 INFO (searcherExecutor-6032-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a857293[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))} [junit4] 2> 1879575 INFO (qtp1191638316-13066) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:58552/solr/collection1/replication&command=fetchindex} status=0 QTime=18 [junit4] 2> 1879581 INFO (qtp1191638316-13059) [ 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> 1879582 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 1879583 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1879584 INFO (qtp1191638316-13061) [ 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> 1879584 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 1879584 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1480965520408 [junit4] 2> 1879584 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 1879584 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 1879584 INFO (qtp640190682-13048) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:58558/solr/collection1/replication&command=fetchindex} status=0 QTime=2 [junit4] 2> 1879586 INFO (qtp640190682-13049) [ 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> 1879586 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[83573CA4F2A6D81B]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 1879588 INFO (qtp2143272057-13009) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 1 [junit4] 2> 1879589 INFO (qtp2143272057-13012) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0 [junit4] 2> 1879590 INFO (qtp2143272057-13007) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0 [junit4] 2> 1879590 INFO (qtp2143272057-13010) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1879590 INFO (qtp2143272057-13010) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@42b99dc8 [junit4] 2> 1879592 INFO (qtp2143272057-13010) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12bdd533[collection1] main] [junit4] 2> 1879592 INFO (qtp2143272057-13010) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1879592 INFO (searcherExecutor-5999-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12bdd533[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C3)))} [junit4] 2> 1879592 INFO (qtp2143272057-13010) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 1879594 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1879595 INFO (qtp2143272057-13011) [ 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> 1879595 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 1879595 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1480965520568 [junit4] 2> 1879595 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 1879595 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1879596 INFO (qtp2143272057-13008) [ 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> 1879597 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 19 [junit4] 2> 1879598 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7567073224138933] [junit4] 2> 1879598 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1879598 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirecto [...truncated too long message...] verseer Overseer (id=97056584955396101-127.0.0.1:59497_solr-n_0000000000) closing [junit4] 2> 2319847 INFO (OverseerStateUpdate-97056584955396101-127.0.0.1:59497_solr-n_0000000000) [n:127.0.0.1:59497_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:59497_solr [junit4] 2> 2319853 WARN (zkCallback-3161-thread-2-processing-n:127.0.0.1:59497_solr) [n:127.0.0.1:59497_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2319854 INFO (jetty-launcher-3151-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@49fdc5f4{/solr,null,UNAVAILABLE} [junit4] 2> 2319855 INFO (zkCallback-3162-thread-1-processing-n:127.0.0.1:59498_solr) [n:127.0.0.1:59498_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2319859 INFO (zkCallback-3162-thread-2-processing-n:127.0.0.1:59498_solr) [n:127.0.0.1:59498_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59498_solr [junit4] 2> 2319859 WARN (zkCallback-3162-thread-1-processing-n:127.0.0.1:59498_solr) [n:127.0.0.1:59498_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2321369 INFO (jetty-launcher-3151-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a90a131{/solr,null,UNAVAILABLE} [junit4] 2> 2321369 INFO (SUITE-DocValuesNotIndexedTest-seed#[83573CA4F2A6D81B]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59495 59495 [junit4] 2> 2321392 INFO (Thread-3607) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59495 59495 [junit4] 2> 2323586 WARN (Thread-3607) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/configs/conf1/managed-schema [junit4] 2> 4 /solr/configs/conf1 [junit4] 2> 3 /solr/aliases.json [junit4] 2> 3 /solr/clusterprops.json [junit4] 2> 2 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 3 /solr/clusterstate.json [junit4] 2> 2 /solr/configs/conf1/managed-schema [junit4] 2> 2 /solr/collections/dv_coll/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 13 /solr/overseer/queue [junit4] 2> 3 /solr/live_nodes [junit4] 2> 3 /solr/collections [junit4] 2> 3 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue-work [junit4] 2> [junit4] 2> 2323586 INFO (SUITE-DocValuesNotIndexedTest-seed#[83573CA4F2A6D81B]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_83573CA4F2A6D81B-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {_version_=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=Lucene50(blocksize=128)}, docValues:{floatField=DocValuesFormat(name=Lucene70), doubleField=DocValuesFormat(name=Memory), intFieldMulti=DocValuesFormat(name=Lucene70), boolFieldMulti=DocValuesFormat(name=Memory), floatGSL=DocValuesFormat(name=Direct), longFieldMulti=DocValuesFormat(name=Lucene70), intField=DocValuesFormat(name=Memory), boolField=DocValuesFormat(name=Direct), stringFieldMulti=DocValuesFormat(name=Direct), dateFieldMulti=DocValuesFormat(name=Lucene70), longGSF=DocValuesFormat(name=Lucene70), doubleFieldMulti=DocValuesFormat(name=Direct), stringGSL=DocValuesFormat(name=Lucene70), doubleGSF=DocValuesFormat(name=Memory), doubleGSL=DocValuesFormat(name=Lucene70), stringGSF=DocValuesFormat(name=Memory), intGSL=DocValuesFormat(name=Memory), longField=DocValuesFormat(name=Lucene70), dateField=DocValuesFormat(name=Direct), dateGSF=DocValuesFormat(name=Direct), boolGSF=DocValuesFormat(name=Direct), longGSL=DocValuesFormat(name=Direct), boolGSL=DocValuesFormat(name=Lucene70), floatFieldMulti=DocValuesFormat(name=Memory), floatGSF=DocValuesFormat(name=Lucene70), dateGSL=DocValuesFormat(name=Lucene70), intGSF=DocValuesFormat(name=Lucene70), stringField=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=18, maxMBSortInHeap=6.3035895680194525, sim=RandomSimilarity(queryNorm=true): {}, locale=mk, timezone=Antarctica/Rothera [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=213377896,total=437780480 [junit4] 2> NOTE: All tests run in this JVM: [TestSimpleTrackingShardHandler, ShowFileRequestHandlerTest, SpellPossibilityIteratorTest, TestBlendedInfixSuggestions, TestFoldingMultitermQuery, HttpSolrCallGetCoreTest, VMParamsZkACLAndCredentialsProvidersTest, PeerSyncReplicationTest, TestCollationFieldDocValues, TestQueryTypes, DistributedFacetPivotSmallAdvancedTest, DistributedExpandComponentTest, TestAnalyzedSuggestions, SuggesterTest, TestSchemaSimilarityResource, CoreAdminHandlerTest, CollectionTooManyReplicasTest, AliasIntegrationTest, TestLMJelinekMercerSimilarityFactory, FileBasedSpellCheckerTest, DistributedVersionInfoTest, ParsingFieldUpdateProcessorsTest, FieldAnalysisRequestHandlerTest, TestOmitPositions, EchoParamsTest, ShufflingReplicaListTransformerTest, TestBlobHandler, HdfsLockFactoryTest, SuggesterWFSTTest, CdcrReplicationHandlerTest, CoreMergeIndexesAdminHandlerTest, TestLocalFSCloudBackupRestore, TestFieldCollectionResource, ShardRoutingCustomTest, TestFastWriter, CdcrVersionReplicationTest, LeaderFailureAfterFreshStartTest, TestHdfsCloudBackupRestore, TestNoOpRegenerator, TestDynamicFieldCollectionResource, TestGeoJSONResponseWriter, DistribJoinFromCollectionTest, OverriddenZkACLAndCredentialsProvidersTest, TestJavabinTupleStreamParser, TestSolrQueryResponse, BlockCacheTest, TestLRUStatsCache, HdfsBasicDistributedZkTest, TermVectorComponentDistributedTest, AnalysisErrorHandlingTest, DeleteInactiveReplicaTest, BlockJoinFacetDistribTest, TestSmileRequest, TestExactSharedStatsCache, CollectionReloadTest, HdfsDirectoryFactoryTest, NoCacheHeaderTest, CoreAdminCreateDiscoverTest, TestJsonFacetRefinement, TestFieldCacheWithThreads, TestCoreDiscovery, BasicDistributedZk2Test, TestStressCloudBlindAtomicUpdates, CleanupOldIndexTest, TestDownShardTolerantSearch, TestEmbeddedSolrServerConstructors, ClusterStateTest, PreAnalyzedFieldManagedSchemaCloudTest, TestManagedResourceStorage, TestCloudInspectUtil, TestDFISimilarityFactory, TestSolrDeletionPolicy2, OverseerModifyCollectionTest, TestRecoveryHdfs, SolrInfoMBeanTest, UpdateParamsTest, SaslZkACLProviderTest, DocValuesMissingTest, ConnectionReuseTest, HdfsSyncSliceTest, TestRandomFaceting, TestLeaderElectionZkExpiry, TestSolrConfigHandlerCloud, AsyncCallRequestStatusResponseTest, IndexSchemaTest, TestMiniSolrCloudCluster, QueryParsingTest, TestBadConfig, TestSolrCoreSnapshots, TestHdfsUpdateLog, TestDeleteCollectionOnDownNodes, DirectUpdateHandlerOptimizeTest, MinimalSchemaTest, ZkSolrClientTest, TestComplexPhraseQParserPlugin, TestFieldSortValues, ReplicationFactorTest, UUIDFieldTest, ConjunctionSolrSpellCheckerTest, TermVectorComponentTest, TestSubQueryTransformer, TestLuceneMatchVersion, DebugComponentTest, CreateCollectionCleanupTest, SharedFSAutoReplicaFailoverTest, SolrCoreCheckLockOnStartupTest, AnalyticsQueryTest, TestSuggestSpellingConverter, TestManagedSchemaAPI, SolrCmdDistributorTest, TestSolr4Spatial, TestPivotHelperCode, DistributedQueryComponentOptimizationTest, TestSizeLimitedDistributedMap, SpellingQueryConverterTest, StatsReloadRaceTest, TestComponentsName, TimerUtilsTest, TestSearcherReuse, SpellCheckComponentTest, AddSchemaFieldsUpdateProcessorFactoryTest, ScriptEngineTest, TestSSLRandomization, LoggingHandlerTest, JavabinLoaderTest, ChaosMonkeySafeLeaderTest, ResourceLoaderTest, TestJoin, DistribCursorPagingTest, TestFuzzyAnalyzedSuggestions, CollectionsAPISolrJTest, TestSolrCloudWithKerberosAlt, SecurityConfHandlerTest, AutoCommitTest, TestManagedStopFilterFactory, RuleEngineTest, CachingDirectoryFactoryTest, TestSQLHandler, SortSpecParsingTest, WordBreakSolrSpellCheckerTest, TestDocSet, SortByFunctionTest, SimpleMLTQParserTest, TestSolrXml, CacheHeaderTest, TestSolrFieldCacheMBean, PreAnalyzedFieldTest, FieldMutatingUpdateProcessorTest, TestFreeTextSuggestions, SimpleCollectionCreateDeleteTest, TestBulkSchemaAPI, ClassificationUpdateProcessorFactoryTest, TestRecovery, LukeRequestHandlerTest, BadIndexSchemaTest, DeleteShardTest, TestQueryUtils, HdfsNNFailoverTest, DeleteNodeTest, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomDVFaceting, ZkCLITest, TestDistributedGrouping, ZkControllerTest, TestReload, DistributedTermsComponentTest, StatsComponentTest, ConvertedLegacyTest, TestFiltering, BasicFunctionalityTest, TestIndexSearcher, SoftAutoCommitTest, TestTrie, TestAtomicUpdateErrorCases, QueryEqualityTest, DistanceFunctionTest, XsltUpdateRequestHandlerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TermsComponentTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, FastVectorHighlighterTest, RegexBoostProcessorTest, UpdateRequestProcessorFactoryTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, SearchHandlerTest, HighlighterConfigTest, TestQuerySenderListener, TestSolrIndexConfig, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestRTGBase, DistributedIntervalFacetingTest, TestHighlightDedupGrouping, CdcrBootstrapTest, DocValuesNotIndexedTest] [junit4] Completed [468/658 (2!)] on J1 in 7.50s, 4 tests, 1 failure <<< FAILURES! [...truncated 63026 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org