Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/536/ Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestMaxPositionInOldIndex Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestMaxPositionInOldIndex_E08C73A66FC4722C-001\maxposindex-001: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestMaxPositionInOldIndex_E08C73A66FC4722C-001\maxposindex-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestMaxPositionInOldIndex_E08C73A66FC4722C-001\maxposindex-001: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestMaxPositionInOldIndex_E08C73A66FC4722C-001\maxposindex-001 at __randomizedtesting.SeedInfo.seed([E08C73A66FC4722C]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication Error Message: expected:<1> but was:<0> Stack Trace: java.lang.AssertionError: expected:<1> but was:<0> at __randomizedtesting.SeedInfo.seed([9DAC53D20E69A725:6ADFBD8AC88108C3]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1331) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 4367 lines...] [junit4] Suite: org.apache.lucene.index.TestMaxPositionInOldIndex [junit4] 2> NOTE: test params are: codec=Lucene62, sim=ClassicSimilarity, locale=uk, timezone=Europe/Berlin [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=50474728,total=101711872 [junit4] 2> NOTE: All tests run in this JVM: [TestLucene50NormsFormat, TestBackwardsCompatibility, TestMaxPositionInOldIndex] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestMaxPositionInOldIndex -Dtests.seed=E08C73A66FC4722C -Dtests.slow=true -Dtests.locale=uk -Dtests.timezone=Europe/Berlin -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] ERROR 0.00s J1 | TestMaxPositionInOldIndex (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestMaxPositionInOldIndex_E08C73A66FC4722C-001\maxposindex-001: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestMaxPositionInOldIndex_E08C73A66FC4722C-001\maxposindex-001 [junit4] > at __randomizedtesting.SeedInfo.seed([E08C73A66FC4722C]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] Completed [3/5 (1!)] on J1 in 0.09s, 1 test, 1 error <<< FAILURES! [...truncated 6618 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\init-core-data-001 [junit4] 2> 314488 INFO (SUITE-TestReplicationHandler-seed#[9DAC53D20E69A725]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 314490 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestDetails [junit4] 2> 314490 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\collection1 [junit4] 2> 314531 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 314532 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@763a3141{/solr,null,AVAILABLE} [junit4] 2> 314535 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Started ServerConnector@34fdaa2c{HTTP/1.1,[http/1.1]}{127.0.0.1:57851} [junit4] 2> 314535 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server Started @323578ms [junit4] 2> 314535 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=57851} [junit4] 2> 314535 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 314535 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0 [junit4] 2> 314535 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 314535 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 314535 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-24T17:15:34.293Z [junit4] 2> 314536 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\solr.xml [junit4] 2> 314550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 314556 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\. [junit4] 2> 314556 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 314598 INFO (coreLoadExecutor-1016-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 314607 INFO (coreLoadExecutor-1016-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 314613 INFO (coreLoadExecutor-1016-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 314621 INFO (coreLoadExecutor-1016-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\.\collection1 [junit4] 2> 314621 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\.\collection1\data\] [junit4] 2> 314622 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=378842631, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 314661 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 314661 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 314661 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19488562083136796] [junit4] 2> 314663 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@22c9e9c0[collection1] main] [junit4] 2> 314663 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-001\collection1\conf [junit4] 2> 314664 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 314664 INFO (coreLoadExecutor-1016-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 314664 INFO (searcherExecutor-1017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@22c9e9c0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 315073 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\collection1 [junit4] 2> 315083 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 315084 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@413c50ea{/solr,null,AVAILABLE} [junit4] 2> 315087 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Started ServerConnector@903dc23{HTTP/1.1,[http/1.1]}{127.0.0.1:57854} [junit4] 2> 315087 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server Started @324131ms [junit4] 2> 315087 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=57854} [junit4] 2> 315087 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 315087 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0 [junit4] 2> 315087 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 315087 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 315087 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-24T17:15:34.845Z [junit4] 2> 315088 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\solr.xml [junit4] 2> 315127 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 315131 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\. [junit4] 2> 315131 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 315162 INFO (coreLoadExecutor-1026-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 315171 INFO (coreLoadExecutor-1026-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 315174 INFO (coreLoadExecutor-1026-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 315176 INFO (coreLoadExecutor-1026-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\.\collection1 [junit4] 2> 315176 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\.\collection1\data\] [junit4] 2> 315201 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=378842631, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 315211 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 315211 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 315211 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19488562083136796] [junit4] 2> 315213 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@646a2aee[collection1] main] [junit4] 2> 315214 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-002\collection1\conf [junit4] 2> 315216 INFO (coreLoadExecutor-1026-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 315216 INFO (searcherExecutor-1027-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@646a2aee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 315643 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 315646 INFO (qtp2119744019-2252) [ 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> 315652 INFO (qtp2119744019-2259) [ 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=1 [junit4] 2> 315652 INFO (qtp784170030-2279) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4 [junit4] 2> 315671 INFO (qtp2119744019-2259) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 16 [junit4] 2> 315672 INFO (qtp2119744019-2257) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 315672 INFO (qtp2119744019-2257) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@230fc804 [junit4] 2> 315678 INFO (qtp2119744019-2257) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@29dd5fe6[collection1] main] [junit4] 2> 315678 INFO (searcherExecutor-1017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@29dd5fe6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))} [junit4] 2> 315678 INFO (qtp2119744019-2257) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 315678 INFO (qtp2119744019-2257) [ 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> 315698 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 315700 INFO (qtp2119744019-2256) [ 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> 315700 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 315700 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477329335430 [junit4] 2> 315700 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 315700 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 315708 INFO (qtp2119744019-2256) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=5 [junit4] 2> 315708 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 315709 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=75.263671875, floorSegmentMB=2.052734375, forceMergeDeletesPctAllowed=2.488098909951715, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 315710 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 315710 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@6e2ed4d4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@383a4452) [junit4] 2> 315711 INFO (qtp2119744019-2269) [ 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> 315713 INFO (qtp2119744019-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315716 INFO (qtp2119744019-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315718 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315721 INFO (qtp2119744019-2258) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315724 INFO (qtp2119744019-2259) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315726 INFO (qtp2119744019-2257) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315755 INFO (qtp2119744019-2255) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315758 INFO (qtp2119744019-2256) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315760 INFO (qtp2119744019-2267) [ 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> 315760 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1632) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@6e2ed4d4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@383a4452) [junit4] 2> 315762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 315764 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 315764 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@30252e35[collection1] main] [junit4] 2> 315765 INFO (searcherExecutor-1027-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@30252e35[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))} [junit4] 2> 315765 INFO (qtp784170030-2276) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:57851/solr/collection1/replication&command=fetchindex} status=0 QTime=84 [junit4] 2> 315779 INFO (qtp2119744019-2254) [ 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> 315780 INFO (qtp784170030-2275) [ 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=11 [junit4] 2> 315783 INFO (qtp2119744019-2269) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 1 [junit4] 2> 315784 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 315785 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@230fc804 [junit4] 2> 315787 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@aa9c6af[collection1] main] [junit4] 2> 315787 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 315787 INFO (searcherExecutor-1017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@aa9c6af[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1) Uninverting(_1(6.3.0):C1)))} [junit4] 2> 315787 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3 [junit4] 2> 315791 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 315793 INFO (qtp2119744019-2258) [ 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> 315793 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 315793 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477329335543 [junit4] 2> 315793 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 315793 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 315797 INFO (qtp2119744019-2259) [ 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=3 [junit4] 2> 315799 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 19 [junit4] 2> 315802 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=75.263671875, floorSegmentMB=2.052734375, forceMergeDeletesPctAllowed=2.488098909951715, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 315802 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 315804 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@ed5c885 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@146a4ea3) [junit4] 2> 315804 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 315804 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 315804 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 315804 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.doc because it already exists [junit4] 2> 315805 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.tim because it already exists [junit4] 2> 315805 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 315805 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.tip because it already exists [junit4] 2> 315805 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 315805 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 315814 INFO (qtp2119744019-2257) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315816 INFO (qtp2119744019-2255) [ 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> 315821 INFO (qtp2119744019-2256) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315830 INFO (qtp2119744019-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315850 INFO (qtp2119744019-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315853 INFO (qtp2119744019-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315854 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315855 INFO (qtp2119744019-2258) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315859 INFO (qtp2119744019-2259) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315861 INFO (qtp2119744019-2257) [ 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> 315861 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1695) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@ed5c885 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@146a4ea3) [junit4] 2> 315863 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics [junit4] 2> java.io.IOException: file "replication.properties" was already written to [junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654) [junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:675) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:487) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 315864 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 315892 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 315895 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e46e5b0[collection1] main] [junit4] 2> 315896 INFO (searcherExecutor-1027-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e46e5b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1) Uninverting(_1(6.3.0):C1)))} [junit4] 2> 315896 INFO (qtp784170030-2274) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:57851/solr/collection1/replication&command=fetchindex} status=0 QTime=108 [junit4] 2> 315903 INFO (qtp2119744019-2257) [ 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> 315903 INFO (qtp784170030-2273) [ 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> 315906 INFO (qtp2119744019-2256) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 315908 INFO (qtp2119744019-2254) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 315908 INFO (qtp2119744019-2254) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@230fc804 [junit4] 2> 315911 INFO (qtp2119744019-2254) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e7edcc0[collection1] main] [junit4] 2> 315912 INFO (searcherExecutor-1017-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e7edcc0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1) Uninverting(_1(6.3.0):C1) Uninverting(_2(6.3.0):C1)))} [junit4] 2> 315912 INFO (qtp2119744019-2254) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 315912 INFO (qtp2119744019-2254) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4 [junit4] 2> 315913 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 315917 INFO (qtp2119744019-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 315917 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 315917 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477329335666 [junit4] 2> 315917 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 315917 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 315920 INFO (qtp2119744019-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=2 [junit4] 2> 315920 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 28 [junit4] 2> 315922 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=75.263671875, floorSegmentMB=2.052734375, forceMergeDeletesPctAllowed=2.488098909951715, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 315923 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 315923 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1c1e30e4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d615de8) [junit4] 2> 315923 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 315923 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 315923 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.doc because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.tim because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.tip because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Asserting_0.tip because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Asserting_0.doc because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Asserting_0.tim because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists [junit4] 2> 315924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists [junit4] 2> 315925 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315928 INFO (qtp2119744019-2258) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315931 INFO (qtp2119744019-2259) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315932 INFO (qtp2119744019-2255) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315934 INFO (qtp2119744019-2255) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315937 INFO (qtp2119744019-2256) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315938 INFO (qtp2119744019-2254) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315940 INFO (qtp2119744019-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315943 INFO (qtp2119744019-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315945 INFO (qtp2119744019-2252) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 315945 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1758) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1c1e30e4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d615de8) [junit4] 2> 315948 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics [junit4] 2> java.io.IOException: file "replication.properties" was already written to [junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654) [junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:675) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:487) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 315949 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 315952 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 315953 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7dc00f62[collection1] main] [junit4] 2> 315953 INFO (searcherExecutor-1027-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7dc00f62[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1) Uninverting(_1(6.3.0):C1) Uninverting(_2(6.3.0):C1)))} [junit4] 2> 315954 INFO (qtp784170030-2272) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:57851/solr/collection1/replication&command=fetchindex} status=0 QTime=40 [junit4] 2> 315956 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\collection1 [junit4] 2> 315966 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 315967 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e0adb91{/solr,null,AVAILABLE} [junit4] 2> 315968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1aeec2ef{HTTP/1.1,[http/1.1]}{127.0.0.1:57861} [junit4] 2> 315968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server Started @325011ms [junit4] 2> 315968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\collection1\data, hostContext=/solr, hostPort=57861} [junit4] 2> 315968 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 315968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0 [junit4] 2> 315968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 315968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 315968 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-24T17:15:35.726Z [junit4] 2> 315969 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\solr.xml [junit4] 2> 315974 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 315980 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\. [junit4] 2> 315981 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 315981 INFO (qtp2119744019-2258) [ 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> 315985 INFO (indexFetcher-1031-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 315985 INFO (indexFetcher-1031-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477329335666 [junit4] 2> 315985 INFO (indexFetcher-1031-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4 [junit4] 2> 315985 INFO (indexFetcher-1031-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 315998 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 316012 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 316014 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 316016 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\.\collection1 [junit4] 2> 316017 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\.\collection1\data\] [junit4] 2> 316020 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=378842631, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 316030 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 316030 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 316032 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19488562083136796] [junit4] 2> 316035 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4dbad313[collection1] main] [junit4] 2> 316036 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-003\collection1\conf [junit4] 2> 316036 WARN (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 316040 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started. [junit4] 2> 316040 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 316040 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 316040 INFO (searcherExecutor-1041-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4dbad313[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 316488 WARN (qtp2048934351-2307) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master [junit4] 2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:57851/solr: Expected mime type application/octet-stream but got text/html. <html> [junit4] 2> <head> [junit4] 2> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> [junit4] 2> <title>Error 404 </title> [junit4] 2> </head> [junit4] 2> <body> [junit4] 2> <h2>HTTP ERROR: 404</h2> [junit4] 2> <p>Problem accessing /solr/replication. Reason: [junit4] 2> <pre> Can not find: /solr/replication</pre></p> [junit4] 2> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.8.v20160314</a><hr/> [junit4] 2> </body> [junit4] 2> </html> [junit4] 2> [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:561) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1713) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:931) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:153) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2211) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:108) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 316489 INFO (qtp2048934351-2307) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4 [junit4] 2> 316490 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1aeec2ef{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 316490 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2056181965 [junit4] 2> 316491 INFO (coreCloseExecutor-1045-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3fb76855 [junit4] 2> 316494 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5e0adb91{/solr,null,UNAVAILABLE} [junit4] 2> 316495 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestDetails [junit4] 2> 316496 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@34fdaa2c{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 316496 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2141132060 [junit4] 2> 316497 INFO (coreCloseExecutor-1047-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2321cb02 [junit4] 2> 316498 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@763a3141{/solr,null,UNAVAILABLE} [junit4] 2> 316501 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@903dc23{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 316501 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1675524692 [junit4] 2> 316502 INFO (coreCloseExecutor-1049-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2af7096f [junit4] 2> 316505 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@413c50ea{/solr,null,UNAVAILABLE} [junit4] 2> 316507 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartup [junit4] 2> 316509 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\collection1 [junit4] 2> 316522 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 316522 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@336451b3{/solr,null,AVAILABLE} [junit4] 2> 316523 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Started ServerConnector@19d7f774{HTTP/1.1,[http/1.1]}{127.0.0.1:57866} [junit4] 2> 316524 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server Started @325568ms [junit4] 2> 316524 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\collection1\data, hostContext=/solr, hostPort=57866} [junit4] 2> 316524 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 316524 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0 [junit4] 2> 316524 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 316524 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 316524 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-24T17:15:36.282Z [junit4] 2> 316525 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\solr.xml [junit4] 2> 316530 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 316537 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\. [junit4] 2> 316537 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 316562 INFO (coreLoadExecutor-1056-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 316576 INFO (coreLoadExecutor-1056-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 316582 INFO (coreLoadExecutor-1056-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 316591 INFO (coreLoadExecutor-1056-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\.\collection1 [junit4] 2> 316592 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\.\collection1\data\] [junit4] 2> 316592 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=378842631, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 316617 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 316617 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 316617 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19488562083136796] [junit4] 2> 316620 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@257381d8[collection1] main] [junit4] 2> 316621 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\collection1\conf [junit4] 2> 316621 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 316621 INFO (coreLoadExecutor-1056-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 316621 INFO (searcherExecutor-1057-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@257381d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 317041 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\collection1 [junit4] 2> 317050 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 317051 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1bb3ff5c{/solr,null,AVAILABLE} [junit4] 2> 317052 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1210d5fb{HTTP/1.1,[http/1.1]}{127.0.0.1:57869} [junit4] 2> 317052 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server Started @326096ms [junit4] 2> 317052 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\collection1\data, hostContext=/solr, hostPort=57869} [junit4] 2> 317053 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 317053 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0 [junit4] 2> 317053 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 317053 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 317053 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-24T17:15:36.811Z [junit4] 2> 317054 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\solr.xml [junit4] 2> 317060 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 317065 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\. [junit4] 2> 317065 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 317077 INFO (coreLoadExecutor-1066-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 317084 INFO (coreLoadExecutor-1066-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 317087 INFO (coreLoadExecutor-1066-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 317090 INFO (coreLoadExecutor-1066-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\.\collection1 [junit4] 2> 317090 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\.\collection1\data\] [junit4] 2> 317091 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=378842631, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 317101 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 317101 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 317102 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19488562083136796] [junit4] 2> 317102 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72339d72[collection1] main] [junit4] 2> 317103 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-005\collection1\conf [junit4] 2> 317105 INFO (coreLoadExecutor-1066-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 317105 INFO (searcherExecutor-1067-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72339d72[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 317107 INFO (qtp1482983560-2325) [ 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> 317108 INFO (indexFetcher-1071-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 317108 INFO (indexFetcher-1071-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 317108 INFO (indexFetcher-1071-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 317568 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1210d5fb{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 317568 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1639643270 [junit4] 2> 317569 INFO (coreCloseExecutor-1072-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4bc857cc [junit4] 2> 317572 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1bb3ff5c{/solr,null,UNAVAILABLE} [junit4] 2> 317581 INFO (qtp1482983560-2330) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 317582 INFO (qtp1482983560-2330) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 1 [junit4] 2> 317584 INFO (qtp1482983560-2331) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 317584 INFO (qtp1482983560-2331) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@766b700d [junit4] 2> 317585 INFO (qtp1482983560-2331) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@39918a8b[collection1] main] [junit4] 2> 317585 INFO (qtp1482983560-2331) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 317585 INFO (searcherExecutor-1057-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39918a8b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 317585 INFO (qtp1482983560-2331) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 317588 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@19d7f774{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 317588 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1515503255 [junit4] 2> 317589 INFO (coreCloseExecutor-1074-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6134aaea [junit4] 2> 317590 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@336451b3{/solr,null,UNAVAILABLE} [junit4] 2> 317593 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 317593 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1dc409d8{/solr,null,AVAILABLE} [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Started ServerConnector@748911b0{HTTP/1.1,[http/1.1]}{127.0.0.1:57874} [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.Server Started @326639ms [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\collection1\data, hostContext=/solr, hostPort=57874} [junit4] 2> 317595 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.3.0 [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-24T17:15:37.353Z [junit4] 2> 317595 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\solr.xml [junit4] 2> 317601 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 317604 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001\solr-instance-004\. [junit4] 2> 317604 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 317615 INFO (coreLoadExecutor-1081-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0 [junit4] 2> 317624 INFO (coreLoadExecutor-1081-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 317625 INFO (coreLoadExecutor-1081-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 317627 INFO (coreLoadExecutor-1081-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration [...truncated too long message...] .nvm because it already exists [junit4] 2> 421121 INFO (qtp1642558208-3320) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421122 INFO (qtp1642558208-3314) [ 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> 421123 INFO (qtp1642558208-3315) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421124 INFO (qtp1642558208-3317) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421125 INFO (qtp1642558208-3318) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421126 INFO (qtp1642558208-3321) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421128 INFO (qtp1642558208-3319) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421129 INFO (qtp1642558208-3320) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421131 INFO (qtp1642558208-3314) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 421132 INFO (qtp1642558208-3315) [ 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> 421132 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1757) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@34691493 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b0de4ce) [junit4] 2> 421134 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics [junit4] 2> java.io.IOException: file "replication.properties" was already written to [junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654) [junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:675) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:487) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 421134 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 421135 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 421135 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@882d3a6[collection1] main] [junit4] 2> 421136 INFO (searcherExecutor-1650-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@882d3a6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C3) Uninverting(_1(6.3.0):C3)))} [junit4] 2> 421136 INFO (qtp1672509071-3296) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:58562/solr/collection1/replication&command=fetchindex} status=0 QTime=21 [junit4] 2> 421138 INFO (qtp1672509071-3311) [ 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> 421138 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 6 docs [junit4] 2> 421139 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@5b5048d0{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 421139 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1343061201 [junit4] 2> 421140 INFO (coreCloseExecutor-1669-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@70df5c22 [junit4] 2> 421143 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@57486159{/solr,null,UNAVAILABLE} [junit4] 2> 421144 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestRepeater [junit4] 2> 421144 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@295953ba{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 421144 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=920344744 [junit4] 2> 421145 INFO (coreCloseExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@ca9ca4a [junit4] 2> 421146 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@718fffd9{/solr,null,UNAVAILABLE} [junit4] 2> 421148 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@10444a39{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 421148 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=452891901 [junit4] 2> 421148 INFO (coreCloseExecutor-1673-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@29b6c90a [junit4] 2> 421150 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[9DAC53D20E69A725]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@24a77aa6{/solr,null,UNAVAILABLE} [junit4] 2> 421152 INFO (SUITE-TestReplicationHandler-seed#[9DAC53D20E69A725]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_9DAC53D20E69A725-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {name=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting), newname=PostingsFormat(name=Asserting)}, docValues:{}, maxPointsInLeafNode=625, maxMBSortInHeap=5.2488098909951715, sim=ClassicSimilarity, locale=sl-SI, timezone=America/Argentina/Mendoza [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=320524448,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [TestNumericTerms32, AssignTest, TestDocBasedVersionConstraints, JSONWriterTest, OverseerCollectionConfigSetProcessorTest, DistributedQueryComponentCustomSortTest, TestExactSharedStatsCache, TestManagedSchemaAPI, SampleTest, TlogReplayBufferedWhileIndexingTest, ConnectionManagerTest, DocExpirationUpdateProcessorFactoryTest, BaseCdcrDistributedZkTest, TestRandomRequestDistribution, TestSolrCloudSnapshots, RequestLoggingTest, TestExpandComponent, TestHdfsCloudBackupRestore, DOMUtilTest, TestSimpleQParserPlugin, DisMaxRequestHandlerTest, DistributedFacetPivotSmallTest, TestRemoteStreaming, CloudMLTQParserTest, TestFastLRUCache, TestRandomCollapseQParserPlugin, TestStressReorder, RecoveryZkTest, TimeZoneUtilsTest, EnumFieldTest, TestRestoreCore, BlockCacheTest, TestManagedResource, PKIAuthenticationIntegrationTest, HighlighterMaxOffsetTest, XsltUpdateRequestHandlerTest, TestBinaryField, PluginInfoTest, BlockJoinFacetRandomTest, TestHdfsUpdateLog, TestRestManager, CdcrRequestHandlerTest, MergeStrategyTest, TestSolrQueryParserDefaultOperatorResource, HdfsBasicDistributedZkTest, RecoveryAfterSoftCommitTest, TestReplicationHandler] [junit4] Completed [109/644 (1!)] on J0 in 106.69s, 15 tests, 1 failure, 1 skipped <<< FAILURES! [...truncated 62128 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org