Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18762/ Java: 32bit/jdk1.8.0_112 -client -XX:+UseSerialGC
3 tests failed. FAILED: org.apache.solr.cloud.DistributedVersionInfoTest.testReplicaVersionHandling Error Message: Captured an uncaught exception in thread: Thread[id=5887, name=Thread-1718, state=RUNNABLE, group=TGRP-DistributedVersionInfoTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5887, name=Thread-1718, state=RUNNABLE, group=TGRP-DistributedVersionInfoTest] at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:CC9A28F1A033A35C]:0) Caused by: java.lang.IllegalArgumentException: bound must be positive at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D]:0) at java.util.Random.nextInt(Random.java:388) at org.apache.solr.cloud.DistributedVersionInfoTest$3.run(DistributedVersionInfoTest.java:204) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestDetails Error Message: Stack Trace: java.lang.NullPointerException at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:6A3EDC7E94104095]:0) at org.apache.solr.handler.TestReplicationHandler.doTestDetails(TestReplicationHandler.java:312) 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.cloud.PeerSyncReplicationTest.test Error Message: PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:36942/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:41947/collection1]> Stack Trace: java.lang.AssertionError: PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:36942/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:41947/collection1]> at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:9837C0D1ACB404E5]: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.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:162) 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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960) 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 10944 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/init-core-data-001 [junit4] 2> 14481 INFO (SUITE-TestReplicationHandler-seed#[1063FF0B0248691D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 14483 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater [junit4] 2> 14483 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1 [junit4] 2> 14489 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 14490 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19b0c8a{/solr,null,AVAILABLE} [junit4] 2> 14491 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@dbf775{HTTP/1.1,[http/1.1]}{127.0.0.1:44786} [junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @15812ms [junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=44786} [junit4] 2> 14492 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:49.255Z [junit4] 2> 14493 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/solr.xml [junit4] 2> 14512 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/. [junit4] 2> 14512 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 14532 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 14552 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 14555 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 14557 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/./collection1 [junit4] 2> 14557 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/./collection1/data/] [junit4] 2> 14559 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0] [junit4] 2> 14574 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 14574 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 14574 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805 [junit4] 2> 14575 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@14cb0f2[collection1] main] [junit4] 2> 14575 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1/conf [junit4] 2> 14576 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 14576 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 14576 INFO (searcherExecutor-62-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14cb0f2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 15059 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1 [junit4] 2> 15062 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 15063 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@194852b{/solr,null,AVAILABLE} [junit4] 2> 15064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@e1b1a9{HTTP/1.1,[http/1.1]}{127.0.0.1:36464} [junit4] 2> 15064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @16384ms [junit4] 2> 15064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=36464} [junit4] 2> 15064 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:49.828Z [junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/solr.xml [junit4] 2> 15083 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/. [junit4] 2> 15083 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 15107 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 15124 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 15127 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 15128 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1 [junit4] 2> 15128 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1/data/] [junit4] 2> 15130 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0] [junit4] 2> 15139 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 15139 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 15139 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805 [junit4] 2> 15140 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11592bd[collection1] main] [junit4] 2> 15140 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/conf [junit4] 2> 15149 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 15149 INFO (searcherExecutor-72-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11592bd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 15162 INFO (qtp23410299-71) [ 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> 15165 INFO (indexFetcher-76-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 15165 INFO (indexFetcher-76-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 15165 INFO (indexFetcher-76-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 15626 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@e1b1a9{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 15626 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=9150439 [junit4] 2> 15626 INFO (coreCloseExecutor-77-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@103629d [junit4] 2> 15628 INFO (coreCloseExecutor-77-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 15629 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 15644 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@194852b{/solr,null,UNAVAILABLE} [junit4] 2> 15645 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 15646 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@743a5c{/solr,null,AVAILABLE} [junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6ee494{HTTP/1.1,[http/1.1]}{127.0.0.1:36031} [junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @16967ms [junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=36031} [junit4] 2> 15647 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:50.410Z [junit4] 2> 15648 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/solr.xml [junit4] 2> 15668 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/. [junit4] 2> 15668 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 15690 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 15706 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 15710 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 15711 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1 [junit4] 2> 15711 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1/data/] [junit4] 2> 15713 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0] [junit4] 2> 15728 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 15728 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 15728 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805 [junit4] 2> 15729 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17cad8c[collection1] main] [junit4] 2> 15729 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/conf [junit4] 2> 15729 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 15729 INFO (searcherExecutor-85-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17cad8c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 16213 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1 [junit4] 2> 16225 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 16226 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e49fbd{/solr,null,AVAILABLE} [junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f35f38{HTTP/1.1,[http/1.1]}{127.0.0.1:37049} [junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @17547ms [junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=37049} [junit4] 2> 16227 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 16228 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:50.990Z [junit4] 2> 16228 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/solr.xml [junit4] 2> 16239 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/. [junit4] 2> 16239 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 16255 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 16270 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 16273 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 16277 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/./collection1 [junit4] 2> 16278 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/./collection1/data/] [junit4] 2> 16279 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0] [junit4] 2> 16289 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 16289 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 16290 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805 [junit4] 2> 16290 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a4f5b5[collection1] main] [junit4] 2> 16290 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1/conf [junit4] 2> 16291 WARN (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 16292 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started. [junit4] 2> 16292 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 16292 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 16292 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a4f5b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 16796 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 6 [junit4] 2> 16798 INFO (qtp23410299-72) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 16800 INFO (qtp23410299-66) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 16804 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 16805 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@11c8fa [junit4] 2> 16810 INFO (qtp23410299-67) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bff1f7[collection1] main] [junit4] 2> 16810 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 16810 INFO (searcherExecutor-62-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1bff1f7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 16810 INFO (qtp23410299-67) [ 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 6 [junit4] 2> 16822 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 16826 INFO (qtp23410299-72) [ 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> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568 [junit4] 2> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 16832 INFO (qtp23410299-66) [ 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=3 [junit4] 2> 16832 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 16835 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 16836 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 16836 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b0fa15 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b56f0c)) [junit4] 2> 16848 INFO (qtp23410299-67) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=3 [junit4] 2> 16855 INFO (qtp23410299-68) [ 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> 16857 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 16859 INFO (qtp23410299-66) [ 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> 16867 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1997) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b0fa15 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b56f0c)) [junit4] 2> 16869 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 16871 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 16873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@aa2f5b[collection1] main] [junit4] 2> 16873 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@aa2f5b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 16873 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44786/solr/collection1/replication&command=fetchindex} status=0 QTime=52 [junit4] 2> 16883 INFO (qtp7757891-128) [ 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=1 [junit4] 2> 16885 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 16887 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 16891 INFO (qtp7757891-126) [ 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> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568 [junit4] 2> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 16894 INFO (qtp7757891-122) [ 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> 16894 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 16897 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 16898 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 16898 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1520137 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1221a8b)) [junit4] 2> 16899 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 16902 INFO (qtp7757891-123) [ 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> 16904 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 16907 INFO (qtp7757891-122) [ 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> 16908 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1997) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1520137 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1221a8b)) [junit4] 2> 16910 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 16912 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 16914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@211afc[collection1] main] [junit4] 2> 16915 INFO (searcherExecutor-85-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@211afc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 16915 INFO (qtp8868313-109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:37049/solr/collection1/replication&command=fetchindex} status=0 QTime=28 [junit4] 2> 16919 INFO (qtp8868313-105) [ 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=1 [junit4] 2> 16920 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 16921 INFO (qtp23410299-71) [ 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> 16923 INFO (qtp23410299-68) [ 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> 16929 WARN (qtp7757891-121) [ 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:634) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1729) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:927) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:166) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2324) [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:345) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296) [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 (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:532) [junit4] 2> ... 36 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused (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> 16933 INFO (qtp7757891-121) [ 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=7 [junit4] 2> 16942 INFO (qtp23410299-72) [ 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> 16943 INFO (qtp7757891-123) [ 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> 16945 WARN (qtp7757891-126) [ 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:634) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1729) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:927) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:166) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2324) [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:345) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296) [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 (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:532) [junit4] 2> ... 36 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused (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> 16947 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 16950 WARN (qtp7757891-122) [ 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:634) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1729) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:927) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:166) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2324) [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:345) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296) [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 (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:532) [junit4] 2> ... 36 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused (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> 16951 INFO (qtp7757891-122) [ 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> 16956 INFO (qtp7757891-128) [ 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> 16956 INFO (qtp8868313-111) [ 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> 16958 INFO (qtp7757891-121) [ 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> 16959 INFO (qtp8868313-104) [ 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> 16962 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 2 [junit4] 2> 16964 INFO (qtp7757891-122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 16965 INFO (qtp7757891-128) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 16966 INFO (qtp7757891-121) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0 [junit4] 2> 16967 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 16967 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a32516 [junit4] 2> 16974 INFO (qtp7757891-126) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11dd95c[collection1] main] [junit4] 2> 16974 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 16974 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11dd95c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):c4)))} [junit4] 2> 16974 INFO (qtp7757891-126) [ 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 7 [junit4] 2> 16976 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 16978 INFO (qtp23410299-67) [ 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> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568 [junit4] 2> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 16981 INFO (qtp23410299-71) [ 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> 16981 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 16982 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1390701 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1867a7d)) [junit4] 2> 16983 INFO (qtp23410299-68) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 16987 INFO (qtp23410299-72) [ 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> 16989 INFO (qtp23410299-67) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 16991 INFO (qtp23410299-71) [ 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> 16992 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=1997) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1390701 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1867a7d)) [junit4] 2> 16992 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher New index installed. Updating index properties... index=index.20170113055951745 [junit4] 2> 16994 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher removing old index directory MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1c17e04 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@346abc)) [junit4] 2> 16995 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 16997 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 16999 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ad6747[collection1] main] [junit4] 2> 16999 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ad6747[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))} [junit4] 2> 16999 INFO (qtp7757891-127) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44786/solr/collection1/replication&command=fetchindex} status=0 QTime=23 [junit4] 2> 17002 INFO (qtp7757891-122) [ 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> 17003 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 17004 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 17006 INFO (qtp7757891-123) [ 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> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568 [junit4] 2> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 17006 INFO (qtp8868313-110) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:37049/solr/collection1/replication&command=fetchindex} status=0 QTime=2 [junit4] 2> 17008 INFO (qtp8868313-109) [ 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> 17010 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 17011 INFO (qtp23410299-66) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 1 [junit4] 2> 17013 INFO (qtp23410299-72) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0 [junit4] 2> 17014 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0 [junit4] 2> 17015 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 17015 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@11c8fa [junit4] 2> 17019 INFO (qtp23410299-71) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@aaeba5[collection1] main] [junit4] 2> 17020 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 17020 INFO (searcherExecutor-62-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@aaeba5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3) Uninverting(_1(7.0.0):c3)))} [junit4] 2> 17020 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5 [junit4] 2> 17022 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 17024 INFO (qtp23410299-68) [ 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> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591778 [junit4] 2> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 17026 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 17027 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7 [junit4] 2> 17029 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@8f6cd2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cc85e9)) [junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 17034 INFO (qtp23410299-67) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 17039 INFO (qtp23410299-71) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 17041 INFO (qtp23410299-68) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 17043 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 17043 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2060) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@8f6cd2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cc85e9)) [junit4] 2> 17047 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 17049 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 17051 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f6b0e[collection1] main] [junit4] 2> 17052 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f6b0e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3) Uninverting(_1(7.0.0):c3)))} [junit4] 2> 17052 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44786/solr/collection1/replication&command=fetchindex} status=0 QTime=29 [junit4] 2> 17054 INFO (qtp7757891-121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=6 status=0 QTime=0 [junit4] 2> 17055 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 6 docs [junit4] 2> 17056 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 17058 INFO (qtp7757891-126) [ 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> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591778 [junit4] 2> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 17061 INFO (qtp7757891-123) [ 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=2 [junit4] 2> 17062 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7 [junit4] 2> 17063 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 17064 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@34f41c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a9cfa1)) [junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 17066 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 17067 INFO (qtp7757891-121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 17070 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 17075 INFO (qtp7757891-123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segm [...truncated too long message...] o.a.s.c.OverseerTaskProcessor According to ZK I (id=97272926003920915-127.0.0.1:36942_-n_0000000004) am no longer a leader. [junit4] 2> 1529891 INFO (OverseerStateUpdate-97272926003920915-127.0.0.1:36942_-n_0000000004) [n:127.0.0.1:36942_ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:36942_ [junit4] 2> 1531392 WARN (zkCallback-4067-thread-2-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1532246 WARN (zkCallback-4067-thread-1-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Closed, skipping sync up. [junit4] 2> 1532247 INFO (zkCallback-4067-thread-1-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1073da5 [junit4] 2> 1532306 INFO (zkCallback-4067-thread-1-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 1532306 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1532307 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1ae53cf{SSL,[ssl, http/1.1]}{127.0.0.1:36942} [junit4] 2> 1532307 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c577af{/,null,UNAVAILABLE} [junit4] 2> 1532308 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 44499 [junit4] 2> 1532309 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46282 46282 [junit4] 2> 1532334 INFO (Thread-2189) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46282 46282 [junit4] 2> 1532335 WARN (Thread-2189) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 5 /solr/security.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/97272926003920906-core_node1-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/97272926003920901-127.0.0.1:33691_-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/97272926003920906-127.0.0.1:41947_-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 45 /solr/overseer/collection-queue-work [junit4] 2> 27 /solr/overseer/queue [junit4] 2> 14 /solr/overseer/queue-work [junit4] 2> 6 /solr/collections [junit4] 2> 5 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=PeerSyncReplicationTest -Dtests.method=test -Dtests.seed=1063FF0B0248691D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh-HK -Dtests.timezone=Europe/Bucharest -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 47.7s J2 | PeerSyncReplicationTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:36942/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:41947/collection1]> [junit4] > at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:9837C0D1ACB404E5]:0) [junit4] > at org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:162) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1532339 INFO (SUITE-PeerSyncReplicationTest-seed#[1063FF0B0248691D]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_1063FF0B0248691D-001 [junit4] 2> Jan 13, 2017 12:25:07 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {other_tl1=FSTOrd50, range_facet_l_dv=BlockTreeOrds(blocksize=128), rnd_s=Lucene50(blocksize=128), multiDefault=Lucene50(blocksize=128), intDefault=FSTOrd50, a_i1=FSTOrd50, range_facet_l=FSTOrd50, _version_=FSTOrd50, a_t=FSTOrd50, id=BlockTreeOrds(blocksize=128), range_facet_i_dv=FSTOrd50, text=FST50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=781, maxMBSortInHeap=5.411463018468438, sim=RandomSimilarity(queryNorm=false): {}, locale=zh-HK, timezone=Europe/Bucharest [junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.8.0_112 (32-bit)/cpus=12,threads=1,free=154295320,total=371957760 [junit4] 2> NOTE: All tests run in this JVM: [TestHighFrequencyDictionaryFactory, TestTrieFacet, TestInitParams, ManagedSchemaRoundRobinCloudTest, CoreAdminRequestStatusTest, DeleteInactiveReplicaTest, DataDrivenBlockJoinTest, TestBlendedInfixSuggestions, ResponseHeaderTest, TestDocTermOrds, SolrCoreMetricManagerTest, TestIntervalFaceting, ShowFileRequestHandlerTest, TestUtils, TestSubQueryTransformerDistrib, TestReversedWildcardFilterFactory, ConnectionReuseTest, SynonymTokenizerTest, TestAddFieldRealTimeGet, CdcrRequestHandlerTest, TestSmileRequest, TestIBSimilarityFactory, AutoCommitTest, HdfsDirectoryFactoryTest, TestChildDocTransformer, BadCopyFieldTest, LeaderInitiatedRecoveryOnShardRestartTest, TestCustomDocTransformer, BasicDistributedZk2Test, MetricsHandlerTest, TestDynamicFieldResource, TestRestManager, CollectionStateFormat2Test, TestPhraseSuggestions, TestMergePolicyConfig, TestImpersonationWithHadoopAuth, TestFieldSortValues, TestCloudPseudoReturnFields, TestGroupingSearch, HighlighterConfigTest, ForceLeaderTest, TestFastOutputStream, TestIndexingPerformance, SolrPluginUtilsTest, HdfsChaosMonkeySafeLeaderTest, TestSolrDeletionPolicy1, TestLeaderInitiatedRecoveryThread, TestNumericTerms32, TestShardHandlerFactory, HLLSerializationTest, MergeStrategyTest, LeaderFailoverAfterPartitionTest, TestHdfsCloudBackupRestore, CoreSorterTest, DistributedQueueTest, TestSolrJ, JSONWriterTest, TemplateUpdateProcessorTest, TestHashQParserPlugin, FacetPivotSmallTest, StatsReloadRaceTest, TestDocSet, TestOverriddenPrefixQueryForCustomFieldType, TestRuleBasedAuthorizationPlugin, TestLuceneMatchVersion, CurrencyFieldXmlFileTest, EchoParamsTest, TestDistributedMissingSort, TestTrackingShardHandlerFactory, ClassificationUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TlogReplayBufferedWhileIndexingTest, TermVectorComponentDistributedTest, TestStressLiveNodes, CircularListTest, HdfsRecoverLeaseTest, TestDistributedStatsComponentCardinality, TestSolrXml, CheckHdfsIndexTest, TestPHPSerializedResponseWriter, TestReloadDeadlock, TestCollationField, RecoveryZkTest, TestConfigOverlay, TestConfig, OverseerModifyCollectionTest, XmlUpdateRequestHandlerTest, TestSolrFieldCacheMBean, TestHdfsBackupRestoreCore, OutOfBoxZkACLAndCredentialsProvidersTest, TestDistribIDF, TestRequestStatusCollectionAPI, GraphQueryTest, TestRecovery, TestTolerantSearch, TestDocBasedVersionConstraints, DirectUpdateHandlerOptimizeTest, TestSchemalessBufferedUpdates, TestLRUStatsCache, MetricUtilsTest, SuggesterTest, TestFieldTypeCollectionResource, AssignTest, BlockJoinFacetDistribTest, DistributedQueryComponentCustomSortTest, PKIAuthenticationIntegrationTest, BadIndexSchemaTest, UUIDUpdateProcessorFallbackTest, RestartWhileUpdatingTest, LukeRequestHandlerTest, PrimitiveFieldTypeTest, BasicDistributedZkTest, CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, ClusterStateUpdateTest, LeaderElectionTest, TestZkChroot, ShardRoutingCustomTest, TestRealTimeGet, TestStressReorder, TestReload, TestCoreContainer, SimpleFacetsTest, ConvertedLegacyTest, TestSort, DirectUpdateHandlerTest, TestIndexSearcher, HighlighterTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, TestCoreDiscovery, SpellCheckCollatorTest, TestFoldingMultitermQuery, DocValuesTest, SolrCoreCheckLockOnStartupTest, TestWordDelimiterFilterFactory, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, TestSurroundQueryParser, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentBuilderTest, FieldAnalysisRequestHandlerTest, RegexBoostProcessorTest, TestCSVResponseWriter, JsonLoaderTest, TestComponentsName, SearchHandlerTest, TestJmxMonitoredMap, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, CleanupOldIndexTest, CollectionsAPISolrJTest, LeaderElectionContextKeyTest, MigrateRouteKeyTest, OverseerRolesTest, OverseerStatusTest, OverseerTaskQueueTest, PeerSyncReplicationTest] [junit4] Completed [497/678 (3!)] on J2 in 48.76s, 1 test, 1 failure <<< FAILURES! [...truncated 55229 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org