Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3822/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication Error Message: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data/index.20170209141348284, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data/snapshot_metadata, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data/index.20170209141348611] expected:<3> but was:<4> Stack Trace: java.lang.AssertionError: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data/index.20170209141348284, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data/snapshot_metadata, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-030/./collection1/data/index.20170209141348611] expected:<3> but was:<4> at __randomizedtesting.SeedInfo.seed([8B7EB6154EEE9495:7C0D584D88063B73]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:918) at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1350) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) 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:368) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11893 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/init-core-data-001 [junit4] 2> 2067239 INFO (SUITE-TestReplicationHandler-seed#[8B7EB6154EEE9495]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 2067246 INFO (SUITE-TestReplicationHandler-seed#[8B7EB6154EEE9495]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) w/ MAC_OS_X supressed clientAuth [junit4] 2> 2067247 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits [junit4] 2> 2067248 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/collection1 [junit4] 2> 2067302 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 2067305 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6305aa0d{/solr,null,AVAILABLE} [junit4] 2> 2067308 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5a6660d9{HTTP/1.1,[http/1.1]}{127.0.0.1:60953} [junit4] 2> 2067308 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server Started @2072161ms [junit4] 2> 2067308 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=60953} [junit4] 2> 2067309 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2067309 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 2067309 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2067309 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2067310 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-09T21:12:12.251Z [junit4] 2> 2067311 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/solr.xml [junit4] 2> 2067465 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/. [junit4] 2> 2067466 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2067518 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2067575 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2067597 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2067601 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/./collection1 [junit4] 2> 2067603 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/./collection1/data/] [junit4] 2> 2067605 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=975961123, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2067744 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2067744 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2067745 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2067745 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a72970d[collection1] main] [junit4] 2> 2067746 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-001/collection1/conf [junit4] 2> 2067747 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 2067747 INFO (coreLoadExecutor-5972-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2067748 INFO (searcherExecutor-5973-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a72970d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2068138 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/collection1 [junit4] 2> 2068148 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 2068151 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1910af8b{/solr,null,AVAILABLE} [junit4] 2> 2068151 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@28bcc483{HTTP/1.1,[http/1.1]}{127.0.0.1:60954} [junit4] 2> 2068151 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server Started @2073004ms [junit4] 2> 2068151 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=60954} [junit4] 2> 2068152 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2068152 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 2068152 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2068152 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2068152 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-09T21:12:13.093Z [junit4] 2> 2068153 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/solr.xml [junit4] 2> 2068221 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/. [junit4] 2> 2068221 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2068253 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2068271 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2068276 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2068281 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/./collection1 [junit4] 2> 2068281 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/./collection1/data/] [junit4] 2> 2068284 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=975961123, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2068344 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2068344 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2068344 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2068344 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d1667e4[collection1] main] [junit4] 2> 2068345 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-002/collection1/conf [junit4] 2> 2068349 INFO (coreLoadExecutor-5982-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 2068349 INFO (searcherExecutor-5983-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d1667e4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2068718 INFO (qtp1189334030-13611) [ 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=1 [junit4] 2> 2068722 INFO (qtp1189334030-13612) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 2 [junit4] 2> 2068723 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2068723 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d15f519 [junit4] 2> 2068730 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a1f489e[collection1] main] [junit4] 2> 2068731 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2068731 INFO (searcherExecutor-5973-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a1f489e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))} [junit4] 2> 2068731 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 8 [junit4] 2> 2068737 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 2068740 INFO (qtp1189334030-13615) [ 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> 2068740 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 2068740 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1486674733664 [junit4] 2> 2068740 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2068740 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 2068749 INFO (qtp1189334030-13616) [ 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=8 [junit4] 2> 2068750 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 2068754 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=40, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.719632895297728] [junit4] 2> 2068755 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2068756 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@2cba83ff lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7900a8da)) [junit4] 2> 2068759 INFO (qtp1189334030-13609) [ 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> 2068764 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneFixedGap_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2068778 INFO (qtp1189334030-13612) [ 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> 2068783 INFO (qtp1189334030-13613) [ 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> 2068790 INFO (qtp1189334030-13614) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneFixedGap_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2068797 INFO (qtp1189334030-13615) [ 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> 2068812 INFO (qtp1189334030-13616) [ 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> 2068820 INFO (qtp1189334030-13609) [ 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> 2068826 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneFixedGap_0.tii&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2068833 INFO (qtp1189334030-13612) [ 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> 2068836 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1687) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@2cba83ff lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7900a8da)) [junit4] 2> 2068839 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=39.2919921875, floorSegmentMB=0.515625, forceMergeDeletesPctAllowed=1.0805423420591964, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1937048169923884 [junit4] 2> 2068842 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2068843 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6fbaa80b[collection1] main] [junit4] 2> 2068843 INFO (searcherExecutor-5983-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6fbaa80b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))} [junit4] 2> 2068843 INFO (qtp1748937838-13628) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:60953/solr/collection1/replication&command=fetchindex} status=0 QTime=108 [junit4] 2> 2068847 INFO (qtp1748937838-13630) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=1 [junit4] 2> 2068848 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 2068849 INFO (qtp1189334030-13613) [ 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> 2068849 INFO (qtp1189334030-13614) [ 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> 2068853 INFO (qtp1189334030-13615) [ 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> 2068854 INFO (qtp1748937838-13631) [ 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> 2068854 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2068855 INFO (qtp1748937838-13632) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2068855 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2068856 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 2068856 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2068856 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 0 [junit4] 2> 2068857 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 2068859 INFO (qtp1189334030-13611) [ 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> 2068859 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 2068859 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1486674733664 [junit4] 2> 2068859 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 2068859 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 2068859 INFO (qtp1748937838-13632) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:60953/solr/collection1/replication&command=fetchindex} status=0 QTime=2 [junit4] 2> 2068860 INFO (qtp1189334030-13612) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 2068861 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2068861 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d15f519 [junit4] 2> 2068864 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@661a393a[collection1] main] [junit4] 2> 2068864 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2068864 INFO (searcherExecutor-5973-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@661a393a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1) Uninverting(_1(7.0.0):C1)))} [junit4] 2> 2068864 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 3 [junit4] 2> 2068866 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 2068867 INFO (qtp1189334030-13614) [ 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> 2068867 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 2068867 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1486674733802 [junit4] 2> 2068867 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 2068867 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 2068869 INFO (qtp1189334030-13615) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 2068869 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 19 [junit4] 2> 2068871 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=40, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.719632895297728] [junit4] 2> 2068872 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2068873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@3efc638b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ec7e612)) [junit4] 2> 2068873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 2068873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.tib because it already exists [junit4] 2> 2068873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 2068873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 2068873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.doc because it already exists [junit4] 2> 2068874 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 2068874 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 2068874 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 2068874 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.tii because it already exists [junit4] 2> 2068876 INFO (qtp1189334030-13609) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_LuceneFixedGap_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2068880 INFO (qtp1189334030-13616) [ 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> 2068881 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_LuceneFixedGap_0.tii&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2068882 INFO (qtp1189334030-13612) [ 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> 2068884 INFO (qtp1189334030-13613) [ 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> 2068885 INFO (qtp1189334030-13614) [ 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> 2068886 INFO (qtp1189334030-13615) [ 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> 2068887 INFO (qtp1189334030-13609) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_LuceneFixedGap_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2068888 INFO (qtp1189334030-13616) [ 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> 2068889 INFO (qtp1189334030-13611) [ 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> 2068890 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1750) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@3efc638b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ec7e612)) [junit4] 2> 2068892 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=39.2919921875, floorSegmentMB=0.515625, forceMergeDeletesPctAllowed=1.0805423420591964, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1937048169923884 [junit4] 2> 2068894 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2068895 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b4209bf[collection1] main] [junit4] 2> 2068896 INFO (searcherExecutor-5983-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b4209bf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1) Uninverting(_1(7.0.0):C1)))} [junit4] 2> 2068896 INFO (qtp1748937838-13626) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:60953/solr/collection1/replication&command=fetchindex} status=0 QTime=30 [junit4] 2> 2068898 INFO (qtp1748937838-13628) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 2068898 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 2068899 INFO (qtp1189334030-13612) [ 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> 2068900 INFO (qtp1189334030-13613) [ 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> 2068901 INFO (qtp1189334030-13614) [ 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> 2068902 INFO (qtp1748937838-13629) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1 [junit4] 2> 2068902 INFO (qtp1189334030-13615) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2068903 INFO (qtp1748937838-13630) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2068904 INFO (qtp1189334030-13609) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 1 [junit4] 2> 2068905 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2068905 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d15f519 [junit4] 2> 2068909 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ed4abc1[collection1] realtime] [junit4] 2> 2068909 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2068909 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 3 [junit4] 2> 2068911 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 2068912 INFO (qtp1189334030-13611) [ 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> 2068912 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 2068912 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1486674733846 [junit4] 2> 2068912 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 2068912 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 2068914 INFO (qtp1189334030-13612) [ 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=1 [junit4] 2> 2068914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 28 [junit4] 2> 2068916 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=40, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.719632895297728] [junit4] 2> 2068917 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2068918 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2068918 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2069929 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2069929 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2070940 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2070940 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2071952 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2071952 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2072983 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2072983 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2074000 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2074000 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2075011 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2075011 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2076028 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2076028 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2077049 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2077049 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2078054 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2078054 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2079065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 2079065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 2080066 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index files to be delete-able [junit4] 2> 2080066 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@649fdbe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@28f7b805)) [junit4] 2> 2080067 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 2080067 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.tib because it already exists [junit4] 2> 2080067 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 2080067 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 2080068 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.doc because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.tii because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_LuceneFixedGap_0.doc because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_LuceneFixedGap_0.tii because it already exists [junit4] 2> 2080069 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists [junit4] 2> 2080070 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists [junit4] 2> 2080070 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists [junit4] 2> 2080070 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists [junit4] 2> 2080070 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_LuceneFixedGap_0.tib because it already exists [junit4] 2> 2080070 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists [junit4] 2> 2080090 INFO (qtp1189334030-13613) [ 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> 2080094 INFO (qtp1189334030-13614) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_LuceneFixedGap_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080096 INFO (qtp1189334030-13615) [ 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> 2080098 INFO (qtp1189334030-13609) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_LuceneFixedGap_0.tii&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080100 INFO (qtp1189334030-13616) [ 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> 2080109 INFO (qtp1189334030-13611) [ 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> 2080110 INFO (qtp1189334030-13612) [ 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> 2080111 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_LuceneFixedGap_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080112 INFO (qtp1189334030-13614) [ 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> 2080114 INFO (qtp1189334030-13615) [ 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> 2080115 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1813) : 11 secs (164 bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@649fdbe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@28f7b805)) [junit4] 2> 2080124 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=39.2919921875, floorSegmentMB=0.515625, forceMergeDeletesPctAllowed=1.0805423420591964, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1937048169923884 [junit4] 2> 2080127 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2080130 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@78a19d62[collection1] main] [junit4] 2> 2080130 INFO (searcherExecutor-5983-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@78a19d62[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1) Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1)))} [junit4] 2> 2080131 INFO (qtp1748937838-13631) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:60953/solr/collection1/replication&command=fetchindex} status=0 QTime=11220 [junit4] 2> 2080135 INFO (qtp1189334030-13609) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2080135 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs [junit4] 2> 2080138 INFO (qtp1748937838-13633) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 2080138 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 2080139 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0 [junit4] 2> 2080140 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2080140 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d15f519 [junit4] 2> 2080156 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@53d8b289[collection1] main] [junit4] 2> 2080156 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2080156 INFO (searcherExecutor-5973-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@53d8b289[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1) Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1) Uninverting(_3(7.0.0):C1)))} [junit4] 2> 2080156 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 16 [junit4] 2> 2080158 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 2080159 INFO (qtp1189334030-13612) [ 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> 2080159 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 5 [junit4] 2> 2080159 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1486674745081 [junit4] 2> 2080159 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4 [junit4] 2> 2080159 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 2080187 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=27 [junit4] 2> 2080187 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 37 [junit4] 2> 2080258 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=40, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.719632895297728] [junit4] 2> 2080266 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2080278 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@72e38522 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4982cf0a)) [junit4] 2> 2080288 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 2080288 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.tib because it already exists [junit4] 2> 2080298 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 2080309 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 2080319 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.doc because it already exists [junit4] 2> 2080319 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 2080331 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 2080332 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 2080334 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneFixedGap_0.tii because it already exists [junit4] 2> 2080334 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_LuceneFixedGap_0.doc because it already exists [junit4] 2> 2080335 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists [junit4] 2> 2080335 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_LuceneFixedGap_0.tii because it already exists [junit4] 2> 2080336 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists [junit4] 2> 2080343 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists [junit4] 2> 2080344 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists [junit4] 2> 2080349 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists [junit4] 2> 2080350 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_LuceneFixedGap_0.tib because it already exists [junit4] 2> 2080350 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists [junit4] 2> 2080350 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.nvd because it already exists [junit4] 2> 2080353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2_LuceneFixedGap_0.tib because it already exists [junit4] 2> 2080353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists [junit4] 2> 2080353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2_LuceneFixedGap_0.tii because it already exists [junit4] 2> 2080353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.fdt because it already exists [junit4] 2> 2080354 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.fnm because it already exists [junit4] 2> 2080354 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.nvm because it already exists [junit4] 2> 2080354 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2_LuceneFixedGap_0.doc because it already exists [junit4] 2> 2080354 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.fdx because it already exists [junit4] 2> 2080361 INFO (qtp1189334030-13614) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080365 INFO (qtp1189334030-13615) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_LuceneFixedGap_0.tii&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080368 INFO (qtp1189334030-13609) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080372 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080374 INFO (qtp1189334030-13611) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_LuceneFixedGap_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080376 INFO (qtp1189334030-13612) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080378 INFO (qtp1189334030-13613) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080379 INFO (qtp1189334030-13614) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080391 INFO (qtp1189334030-13615) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_LuceneFixedGap_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080393 INFO (qtp1189334030-13609) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2080393 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1876) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@72e38522 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4982cf0a)) [junit4] 2> 2080414 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=39.2919921875, floorSegmentMB=0.515625, forceMergeDeletesPctAllowed=1.0805423420591964, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1937048169923884 [junit4] 2> 2080423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 2080453 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d92b7e5[collection1] main] [junit4] 2> 2080453 INFO (searcherExecutor-5983-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d92b7e5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1) Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1) Uninverting(_3(7.0.0):C1)))} [junit4] 2> 2080454 INFO (qtp1748937838-13632) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:60953/solr/collection1/replication&command=fetchindex} status=0 QTime=296 [junit4] 2> 2080458 INFO (qtp1189334030-13616) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=2 [junit4] 2> 2080459 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs [junit4] 2> 2080460 INFO (qtp1748937838-13626) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=1 [junit4] 2> 2080461 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs [junit4] 2> 2080519 INFO (qtp1189334030-13611) [ 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=57 [junit4] 2> 2080522 INFO (qtp1189334030-13612) [ 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> 2080528 INFO (qtp1189334030-13613) [ 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=3 [junit4] 2> 2080528 INFO (qtp1748937838-13628) [ 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=5 [junit4] 2> 2080529 INFO (qtp1189334030-13614) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2080530 INFO (qtp1748937838-13629) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2080530 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits [junit4] 2> 2080531 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5a6660d9{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 2080531 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=376123576 [junit4] 2> 2080532 INFO (coreCloseExecutor-5992-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7b12171a [junit4] 2> 2080533 INFO (coreCloseExecutor-5992-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 2080534 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 2080541 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6305aa0d{/solr,null,UNAVAILABLE} [junit4] 2> 2080543 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@28bcc483{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 2080543 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1516804334 [junit4] 2> 2080544 INFO (coreCloseExecutor-5994-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@44813ceb [junit4] 2> 2080545 INFO (coreCloseExecutor-5994-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 2080546 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 2080547 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1910af8b{/solr,null,UNAVAILABLE} [junit4] 2> 2080551 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexFetchWithMasterUrl [junit4] 2> 2080552 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/collection1 [junit4] 2> 2080567 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 2080569 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@53aa1c70{/solr,null,AVAILABLE} [junit4] 2> 2080569 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@684e84fd{HTTP/1.1,[http/1.1]}{127.0.0.1:61010} [junit4] 2> 2080569 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server Started @2085422ms [junit4] 2> 2080570 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=61010} [junit4] 2> 2080570 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2080570 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 2080570 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2080570 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2080570 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-09T21:12:25.511Z [junit4] 2> 2080572 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/solr.xml [junit4] 2> 2081137 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/. [junit4] 2> 2081137 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2081156 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2081163 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2081166 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2081170 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/./collection1 [junit4] 2> 2081170 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/./collection1/data/] [junit4] 2> 2081171 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=975961123, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2081229 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2081229 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2081230 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2081230 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bf36c29[collection1] main] [junit4] 2> 2081230 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-003/collection1/conf [junit4] 2> 2081231 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 2081231 INFO (coreLoadExecutor-6001-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2081231 INFO (searcherExecutor-6002-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@bf36c29[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2081599 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/collection1 [junit4] 2> 2081618 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 2081620 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68d90b2d{/solr,null,AVAILABLE} [junit4] 2> 2081621 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9e09ab4{HTTP/1.1,[http/1.1]}{127.0.0.1:61011} [junit4] 2> 2081621 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server Started @2086474ms [junit4] 2> 2081621 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=61011} [junit4] 2> 2081622 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2081622 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 2081622 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2081622 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2081622 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-02-09T21:12:26.563Z [junit4] 2> 2081623 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/solr.xml [junit4] 2> 2081682 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/. [junit4] 2> 2081682 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2081697 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2081710 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2081718 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 2081724 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/./collection1 [junit4] 2> 2081725 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/./collection1/data/] [junit4] 2> 2081727 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=975961123, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2082016 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2082016 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2082016 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2082017 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@45be4499[collection1] main] [junit4] 2> 2082017 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8B7EB6154EEE9495-001/solr-instance-004/collection1/conf [junit4] 2> 2082020 INFO (coreLoadExecutor-6011-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 2082020 INFO (searcherExecutor-6012-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@45be4499[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2082023 INFO (qtp1188327178-13662) [ 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> 2082024 INFO (indexFetcher-6016-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 2082024 INFO (indexFetcher-6016-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 2082024 INFO (indexFetcher-6016-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2082533 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@9e09ab4{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 2082533 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=357007182 [junit4] 2> 2082533 INFO (coreCloseExecutor-6017-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@53d72bc0 [junit4] 2> 2082535 INFO (coreCloseExecutor-6017-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 2082536 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 2082537 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@68d90b2d{/solr,null,UNAVAILABLE} [junit4] 2> 2082539 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 2082540 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d595bf4{/solr,null,AVAILABLE} [junit4] 2> 2082541 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8B7EB6154EEE9495]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@79234450{HTTP/1.1,[http/1.1]}{127.0.0.1:61013} [junit4] 2> 2082541 INFO (TEST-TestReplicationHandler.doTestIndexFetchW [...truncated too long message...] kins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/response/BinaryResponseWriter.java (at line 51) [ecj-lint] new JavaBinCodec(resolver).setWritableDocFields(resolver).marshal(response.getValues(), out); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] 24. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/response/BinaryResponseWriter.java (at line 163) [ecj-lint] new JavaBinCodec(resolver).setWritableDocFields(resolver).marshal(rsp.getValues(), out); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] 25. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/response/BinaryResponseWriter.java (at line 166) [ecj-lint] return (NamedList<Object>) new JavaBinCodec(resolver).unmarshal(in); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 26. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/response/SmileResponseWriter.java (at line 33) [ecj-lint] new SmileWriter(out, request, response).writeResponse(); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 27. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/schema/OpenExchangeRatesOrgProvider.java (at line 146) [ecj-lint] ratesJsonStream = resourceLoader.openResource(ratesFileLocation); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'ratesJsonStream' is not closed at this location [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 28. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/CollapsingQParserPlugin.java (at line 1277) [ecj-lint] UninvertingReader uninvertingReader = new UninvertingReader(new ReaderWrapper(searcher.getSlowAtomicReader(), collapseField), mapping); [ecj-lint] ^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'uninvertingReader' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 29. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/CursorMark.java (at line 188) [ecj-lint] pieces = (List<Object>) new JavaBinCodec().unmarshal(in); [ecj-lint] ^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] 30. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/CursorMark.java (at line 265) [ecj-lint] new JavaBinCodec().marshal(marshalledValues, out); [ecj-lint] ^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 31. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 145) [ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len); [ecj-lint] ^ [ecj-lint] Resource leak: 'h' is never closed [ecj-lint] ---------- [ecj-lint] 32. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 154) [ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len); [ecj-lint] ^ [ecj-lint] Resource leak: 'h' is never closed [ecj-lint] ---------- [ecj-lint] 33. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 163) [ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len); [ecj-lint] ^ [ecj-lint] Resource leak: 'h' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 34. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/function/OrdFieldSource.java (at line 78) [ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o; [ecj-lint] ^^ [ecj-lint] Resource leak: 'is' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 35. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/function/ReverseOrdFieldSource.java (at line 78) [ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o; [ecj-lint] ^^ [ecj-lint] Resource leak: 'is' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 36. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/servlet/SolrDispatchFilter.java (at line 263) [ecj-lint] throw new SolrException(ErrorCode.SERVER_ERROR, "Error occurred while loading solr.xml from zookeeper", e); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'loader' is not closed at this location [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 37. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/CdcrTransactionLog.java (at line 98) [ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos); [ecj-lint] ^^ [ecj-lint] Resource leak: 'is' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 38. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java (at line 189) [ecj-lint] LogCodec codec = new LogCodec(resolver); [ecj-lint] ^^^^^ [ecj-lint] Resource leak: 'codec' is never closed [ecj-lint] ---------- [ecj-lint] 39. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java (at line 259) [ecj-lint] LogCodec codec = new LogCodec(resolver); [ecj-lint] ^^^^^ [ecj-lint] Resource leak: 'codec' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 40. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 226) [ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos); [ecj-lint] ^^ [ecj-lint] Resource leak: 'is' is never closed [ecj-lint] ---------- [ecj-lint] 41. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 226) [ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos); [ecj-lint] ^^ [ecj-lint] Resource leak: 'is' is never closed [ecj-lint] ---------- [ecj-lint] 42. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 258) [ecj-lint] LogCodec codec = new LogCodec(resolver); [ecj-lint] ^^^^^ [ecj-lint] Resource leak: 'codec' is never closed [ecj-lint] ---------- [ecj-lint] 43. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 273) [ecj-lint] LogCodec codec = new LogCodec(resolver); [ecj-lint] ^^^^^ [ecj-lint] Resource leak: 'codec' is never closed [ecj-lint] ---------- [ecj-lint] 44. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 527) [ecj-lint] LogCodec codec = new LogCodec(resolver); [ecj-lint] ^^^^^ [ecj-lint] Resource leak: 'codec' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 45. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 655) [ecj-lint] BAOS bos = new BAOS(); [ecj-lint] ^^^ [ecj-lint] Resource leak: 'bos' is never closed [ecj-lint] ---------- [ecj-lint] 46. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 655) [ecj-lint] BAOS bos = new BAOS(); [ecj-lint] ^^^ [ecj-lint] Resource leak: 'bos' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 47. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/util/SolrLogLayout.java (at line 149) [ecj-lint] SolrQueryRequest req = requestInfo == null ? null : requestInfo.getReq(); [ecj-lint] ^^^ [ecj-lint] Resource leak: 'req' is never closed [ecj-lint] ---------- [ecj-lint] 47 problems (2 errors, 45 warnings) BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:775: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:101: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build.xml:674: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:2004: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:2043: Compile failed; see the compiler error output for details. Total time: 117 minutes 18 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org