You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/01/24 04:11:10 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 1634 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1634/
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestDetails
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([B6D340F54DF7ED2F:CC8E6380DBAFC4A7]:0)
at org.apache.solr.handler.TestReplicationHandler.doTestDetails(TestReplicationHandler.java:312)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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 11866 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/init-core-data-001
[junit4] 2> 1171666 INFO (SUITE-TestReplicationHandler-seed#[B6D340F54DF7ED2F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 1171668 INFO (SUITE-TestReplicationHandler-seed#[B6D340F54DF7ED2F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] IGNOR/A 0.00s J2 | TestReplicationHandler.doTestIndexFetchOnMasterRestart
[junit4] > Assumption #1: 'awaitsfix' test group is disabled (@AwaitsFix(bugUrl=https://issues.apache.org/jira/browse/SOLR-9036))
[junit4] 2> 1171681 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
[junit4] 2> 1171682 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1
[junit4] 2> 1171689 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1171693 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42eca012{/solr,null,AVAILABLE}
[junit4] 2> 1171694 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@326a535b{HTTP/1.1,[http/1.1]}{127.0.0.1:49592}
[junit4] 2> 1171694 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server Started @1175696ms
[junit4] 2> 1171694 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=49592}
[junit4] 2> 1171694 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1171694 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1171694 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1171694 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1171694 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-24T03:28:02.484Z
[junit4] 2> 1171695 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/solr.xml
[junit4] 2> 1171716 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/.
[junit4] 2> 1171716 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1171737 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1171747 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1171751 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1171752 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/./collection1
[junit4] 2> 1171753 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1171755 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1171765 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1171765 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1171766 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1171766 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@74d56046[collection1] main]
[junit4] 2> 1171767 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1/conf
[junit4] 2> 1171767 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1171767 INFO (coreLoadExecutor-4381-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1171768 INFO (searcherExecutor-4382-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74d56046[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1172524 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1
[junit4] 2> 1172527 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1172528 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2d7a2e4e{/solr,null,AVAILABLE}
[junit4] 2> 1172528 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d2df843{HTTP/1.1,[http/1.1]}{127.0.0.1:34472}
[junit4] 2> 1172528 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server Started @1176531ms
[junit4] 2> 1172529 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=34472}
[junit4] 2> 1172529 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1172529 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1172529 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1172529 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1172529 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-24T03:28:03.319Z
[junit4] 2> 1172530 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/solr.xml
[junit4] 2> 1172544 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/.
[junit4] 2> 1172544 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1172560 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1172568 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1172570 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1172571 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/./collection1
[junit4] 2> 1172572 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1172573 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1172582 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1172582 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1172582 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1172583 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@53e84929[collection1] main]
[junit4] 2> 1172583 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1/conf
[junit4] 2> 1172585 INFO (coreLoadExecutor-4391-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1172585 INFO (searcherExecutor-4392-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@53e84929[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1172588 INFO (qtp1803179718-8704) [ 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> 1172589 INFO (indexFetcher-4396-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1172589 INFO (indexFetcher-4396-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1172589 INFO (indexFetcher-4396-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1173331 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1173333 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 1173334 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1173334 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
[junit4] 2> 1173339 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e458675[collection1] main]
[junit4] 2> 1173339 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1173339 INFO (searcherExecutor-4382-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e458675[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 1173339 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 1173343 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1173344 INFO (qtp1803179718-8707) [ 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> 1173344 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1173344 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1485228484124
[junit4] 2> 1173344 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1173344 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1173347 INFO (qtp1803179718-8703) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=2
[junit4] 2> 1173347 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 1173348 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1173351 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1173351 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@3fe06969 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@912194f))
[junit4] 2> 1173352 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1173353 INFO (qtp1803179718-8708) [ 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> 1173354 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1173357 INFO (qtp1803179718-8709) [ 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> 1173358 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2329) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@3fe06969 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@912194f))
[junit4] 2> 1173359 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49056111239663813
[junit4] 2> 1173361 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1173362 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ebed524[collection1] main]
[junit4] 2> 1173362 INFO (searcherExecutor-4392-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ebed524[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 1173363 INFO (qtp1941550639-8723) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex} status=0 QTime=21
[junit4] 2> 1173368 INFO (qtp1941550639-8725) [ 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> 1173368 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1173369 INFO (qtp1803179718-8710) [ 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> 1173370 INFO (qtp1803179718-8703) [ 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> 1173371 INFO (qtp1803179718-8707) [ 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> 1173372 INFO (qtp1941550639-8725) [ 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> 1173372 INFO (qtp1803179718-8703) [ 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> 1173373 INFO (qtp1941550639-8727) [ 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> 1173373 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1173373 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1173373 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1173374 INFO (qtp1803179718-8705) [ 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> 1173377 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1173378 INFO (qtp1803179718-8708) [ 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> 1173378 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1173378 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1485228484124
[junit4] 2> 1173378 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1173378 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 1173378 INFO (qtp1941550639-8724) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex} status=0 QTime=4
[junit4] 2> 1173380 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 1
[junit4] 2> 1173381 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1173381 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
[junit4] 2> 1173385 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@578ae5e[collection1] main]
[junit4] 2> 1173385 INFO (searcherExecutor-4382-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@578ae5e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 1173385 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1173385 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 1173386 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1173387 INFO (qtp1803179718-8708) [ 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> 1173388 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 1173388 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1485228484171
[junit4] 2> 1173388 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1173388 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1173389 INFO (qtp1803179718-8708) [ 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> 1173390 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7
[junit4] 2> 1173391 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1173392 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1173393 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1e76f7fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d256b5a))
[junit4] 2> 1173393 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 1173393 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1173393 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 1173393 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1173397 INFO (qtp1803179718-8710) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1173398 INFO (qtp1803179718-8709) [ 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> 1173399 INFO (qtp1803179718-8704) [ 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> 1173399 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2392) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1e76f7fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d256b5a))
[junit4] 2> 1173401 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49056111239663813
[junit4] 2> 1173403 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1173405 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@71f54465[collection1] main]
[junit4] 2> 1173405 INFO (searcherExecutor-4392-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71f54465[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 1173405 INFO (qtp1941550639-8723) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex} status=0 QTime=19
[junit4] 2> 1173407 INFO (qtp1941550639-8720) [ 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> 1173407 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1173407 INFO (qtp1803179718-8707) [ 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> 1173408 INFO (qtp1803179718-8708) [ 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> 1173413 INFO (qtp1803179718-8703) [ 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> 1173414 INFO (qtp1941550639-8726) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4
[junit4] 2> 1173414 INFO (qtp1803179718-8705) [ 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> 1173415 INFO (qtp1941550639-8725) [ 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> 1173415 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1173416 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1173416 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
[junit4] 2> 1173420 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@78be0591[collection1] realtime]
[junit4] 2> 1173420 INFO (qtp1803179718-8704) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1173420 INFO (qtp1803179718-8704) [ 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> 1173421 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1173422 INFO (qtp1803179718-8710) [ 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> 1173422 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 1173422 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1485228484206
[junit4] 2> 1173422 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 1173422 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1173424 INFO (qtp1803179718-8710) [ 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> 1173424 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 1173425 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1173427 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1173428 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1173428 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> 1174428 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1174428 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> 1175429 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1175429 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> 1176434 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1176434 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> 1177434 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1177434 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> 1178435 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1178435 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> 1179436 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1179436 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> 1180436 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1180437 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> 1181437 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1181437 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> 1182438 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1182438 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> 1183439 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1183439 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> 1184440 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> 1184440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@2d8561c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@197167d9))
[junit4] 2> 1184440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 1184440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1184440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 1184440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 1184440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 1184440 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 1184442 INFO (qtp1803179718-8703) [ 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> 1184443 INFO (qtp1803179718-8705) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1184444 INFO (qtp1803179718-8709) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1184445 INFO (qtp1803179718-8704) [ 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> 1184445 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2455) : 11 secs (223 bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@2d8561c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@197167d9))
[junit4] 2> 1184447 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49056111239663813
[junit4] 2> 1184449 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1184452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a4aba8a[collection1] main]
[junit4] 2> 1184452 INFO (searcherExecutor-4392-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a4aba8a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 1184452 INFO (qtp1941550639-8721) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex} status=0 QTime=11031
[junit4] 2> 1184455 INFO (qtp1803179718-8707) [ 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> 1184455 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 1184457 INFO (qtp1941550639-8727) [ 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> 1184457 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1184458 INFO (qtp1803179718-8710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1184458 INFO (qtp1803179718-8703) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1184458 INFO (qtp1803179718-8703) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
[junit4] 2> 1184462 INFO (qtp1803179718-8703) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d46402e[collection1] main]
[junit4] 2> 1184462 INFO (qtp1803179718-8703) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1184462 INFO (searcherExecutor-4382-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d46402e[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> 1184462 INFO (qtp1803179718-8703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 1184463 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1184464 INFO (qtp1803179718-8708) [ 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> 1184465 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 5
[junit4] 2> 1184465 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1485228495248
[junit4] 2> 1184465 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4
[junit4] 2> 1184465 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1184466 INFO (qtp1803179718-8709) [ 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=1
[junit4] 2> 1184467 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 13
[junit4] 2> 1184468 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1184470 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@7dfe9a3a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46f0cea3))
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.cfe because it already exists
[junit4] 2> 1184471 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.cfs because it already exists
[junit4] 2> 1184472 INFO (qtp1803179718-8704) [ 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> 1184473 INFO (qtp1803179718-8707) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1184474 INFO (qtp1803179718-8710) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1184474 INFO (qtp1803179718-8703) [ 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> 1184475 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2518) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@7dfe9a3a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46f0cea3))
[junit4] 2> 1184476 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49056111239663813
[junit4] 2> 1184479 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1184483 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3b329745[collection1] main]
[junit4] 2> 1184483 INFO (searcherExecutor-4392-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b329745[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> 1184483 INFO (qtp1941550639-8724) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex} status=0 QTime=20
[junit4] 2> 1184486 INFO (qtp1803179718-8705) [ 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> 1184486 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 1184487 INFO (qtp1941550639-8723) [ 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=0
[junit4] 2> 1184487 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 1184488 INFO (qtp1803179718-8709) [ 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> 1184489 INFO (qtp1803179718-8704) [ 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> 1184490 INFO (qtp1803179718-8708) [ 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> 1184490 INFO (qtp1941550639-8726) [ 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> 1184491 INFO (qtp1803179718-8707) [ 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> 1184492 INFO (qtp1941550639-8725) [ 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> 1184492 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
[junit4] 2> 1184493 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@326a535b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1184493 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1302978184
[junit4] 2> 1184494 INFO (coreCloseExecutor-4401-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@41500bbb
[junit4] 2> 1184494 INFO (coreCloseExecutor-4401-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1184495 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1184495 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@42eca012{/solr,null,UNAVAILABLE}
[junit4] 2> 1184496 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7d2df843{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1184497 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1491076348
[junit4] 2> 1184497 INFO (coreCloseExecutor-4403-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4de9a5e5
[junit4] 2> 1184498 INFO (coreCloseExecutor-4403-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1184499 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1184499 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2d7a2e4e{/solr,null,UNAVAILABLE}
[junit4] 2> 1184501 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterWrite2Slave
[junit4] 2> 1184502 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1
[junit4] 2> 1184507 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1184508 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6469b350{/solr,null,AVAILABLE}
[junit4] 2> 1184508 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@280b12b5{HTTP/1.1,[http/1.1]}{127.0.0.1:47033}
[junit4] 2> 1184508 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server Started @1188511ms
[junit4] 2> 1184508 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=47033}
[junit4] 2> 1184509 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1184509 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1184509 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1184509 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1184509 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-24T03:28:15.299Z
[junit4] 2> 1184510 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/solr.xml
[junit4] 2> 1184525 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/.
[junit4] 2> 1184525 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1184538 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1184547 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1184562 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1184564 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/./collection1
[junit4] 2> 1184564 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/./collection1/data/]
[junit4] 2> 1184565 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1184575 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1184575 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1184576 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1184576 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f6bde87[collection1] main]
[junit4] 2> 1184576 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1/conf
[junit4] 2> 1184577 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1184577 INFO (coreLoadExecutor-4410-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1184577 INFO (searcherExecutor-4411-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3f6bde87[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1185311 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1
[junit4] 2> 1185314 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1185315 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5c4711ab{/solr,null,AVAILABLE}
[junit4] 2> 1185316 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7b6995aa{HTTP/1.1,[http/1.1]}{127.0.0.1:38527}
[junit4] 2> 1185316 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.Server Started @1189318ms
[junit4] 2> 1185316 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=38527}
[junit4] 2> 1185317 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1185317 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1185317 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1185317 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1185317 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-24T03:28:16.107Z
[junit4] 2> 1185318 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/solr.xml
[junit4] 2> 1185327 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/.
[junit4] 2> 1185327 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1185335 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1185343 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1185345 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1185346 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/./collection1
[junit4] 2> 1185347 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/./collection1/data/]
[junit4] 2> 1185348 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1185357 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1185357 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1185357 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1185358 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b0d57b[collection1] main]
[junit4] 2> 1185358 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1/conf
[junit4] 2> 1185362 INFO (coreLoadExecutor-4420-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1185363 INFO (searcherExecutor-4421-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b0d57b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1185366 INFO (qtp439187683-8758) [ 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> 1185366 INFO (indexFetcher-4425-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1185366 INFO (indexFetcher-4425-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1185366 INFO (indexFetcher-4425-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1186116 INFO (qtp439187683-8759) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1186118 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 1186118 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1186119 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1186119 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1186120 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1186120 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1186120 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1186121 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1186121 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1186122 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1186129 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 7
[junit4] 2> 1186134 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1186134 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1186134 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1186135 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1186135 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1186136 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1186136 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1186137 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1186137 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1186138 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1186141 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 2
[junit4] 2> 1186141 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1186142 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1186142 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1186143 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1186143 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1186144 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1186144 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1186144 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1186145 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1186148 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1186151 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 2
[junit4] 2> 1186152 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1186153 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1186153 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1186154 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1186154 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1186154 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1186155 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1186155 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1186156 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1186156 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1186159 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 2
[junit4] 2> 1186160 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1186160 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1186160 INFO (qtp439187683-8761) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1186161 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1186162 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1186163 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1186163 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1186164 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1186165 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1186166 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1186168 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 2
[junit4] 2> 1186169 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1186169 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1186170 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1186170 INFO (qtp439187683-8754) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1186170 INFO (qtp439187683-8759) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1186171 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1186171 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1186172 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1186172 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1186172 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1186175 INFO (qtp439187683-8755) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 2
[junit4] 2> 1186175 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1186176 INFO (qtp439187683-8756) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1186176 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1186177 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1186177 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1186177 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1186178 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1186178 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1186179 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1186179 INFO (qtp439187683-8758) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1186182 INFO (qtp439187683-8758) [ x:
[...truncated too long message...]
s=0 QTime=0
[junit4] 2> 1267067 INFO (indexFetcher-5008-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1267067 INFO (indexFetcher-5008-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1485228577566
[junit4] 2> 1267067 INFO (indexFetcher-5008-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1267068 INFO (indexFetcher-5008-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 1267068 INFO (Thread-2492) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=56.29296875, floorSegmentMB=0.474609375, forceMergeDeletesPctAllowed=16.35803033493523, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1267069 INFO (Thread-2492) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1267070 INFO (Thread-2492) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72ff9517[collection1] main]
[junit4] 2> 1267070 INFO (Thread-2492) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38c949a6
[junit4] 2> 1267071 INFO (searcherExecutor-5004-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72ff9517[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 1267072 INFO (Thread-2492) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1267088 INFO (qtp1298531011-9743) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1267088 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.h.TestReplicationHandler Waited for 300ms and found 1 docs
[junit4] 2> 1267089 INFO (qtp728390470-9725) [ 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> 1267090 INFO (qtp728390470-9721) [ 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> 1267091 INFO (qtp728390470-9727) [ 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> 1267092 INFO (qtp1298531011-9743) [ 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> 1267092 INFO (qtp728390470-9724) [ 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> 1267092 INFO (qtp1298531011-9751) [ 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> 1267093 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestIndexAndConfigReplication
[junit4] 2> 1267103 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@218c68b0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1267103 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=191716058
[junit4] 2> 1267104 INFO (coreCloseExecutor-5009-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5f8d0e67
[junit4] 2> 1267104 INFO (coreCloseExecutor-5009-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1267105 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1267105 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@83fe836{/solr,null,UNAVAILABLE}
[junit4] 2> 1267125 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@26c29fc4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1267125 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=105289717
[junit4] 2> 1267126 INFO (coreCloseExecutor-5011-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3b3467dc
[junit4] 2> 1267137 INFO (coreCloseExecutor-5011-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1267146 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1267146 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1de90358{/solr,null,UNAVAILABLE}
[junit4] 2> 1267156 INFO (SUITE-TestReplicationHandler-seed#[B6D340F54DF7ED2F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), newname=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{}, maxPointsInLeafNode=1977, maxMBSortInHeap=6.229491111302826, sim=RandomSimilarity(queryNorm=false): {}, locale=el-GR, timezone=Europe/Kirov
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=4,threads=1,free=138151456,total=533725184
[junit4] 2> NOTE: All tests run in this JVM: [CoreAdminCreateDiscoverTest, HdfsDirectoryTest, RankQueryTest, StatelessScriptUpdateProcessorFactoryTest, TestCustomStream, SolrIndexConfigTest, TestSubQueryTransformerCrossCore, TestIndexSearcher, PeerSyncWithIndexFingerprintCachingTest, XmlUpdateRequestHandlerTest, MBeansHandlerTest, ConjunctionSolrSpellCheckerTest, TestInfoStreamLogging, DistributedSuggestComponentTest, BasicAuthIntegrationTest, SharedFSAutoReplicaFailoverTest, RulesTest, TestIBSimilarityFactory, TestSchemaVersionResource, TestReload, ShowFileRequestHandlerTest, TestRandomRequestDistribution, TestComplexPhraseQParserPlugin, TestConfig, TestReqParamsAPI, UpdateParamsTest, FieldAnalysisRequestHandlerTest, URLClassifyProcessorTest, ConnectionManagerTest, TestFastOutputStream, PreAnalyzedFieldTest, TestRequestForwarding, CircularListTest, TestZkChroot, WrapperMergePolicyFactoryTest, OverseerCollectionConfigSetProcessorTest, UnloadDistributedZkTest, BasicDistributedZk2Test, TestFieldCacheWithThreads, TestSolrQueryResponse, JvmMetricsTest, TestLuceneMatchVersion, BinaryUpdateRequestHandlerTest, TestPushWriter, ExternalFileFieldSortTest, SolrCLIZkUtilsTest, TestManagedSynonymFilterFactory, TestBackupRepositoryFactory, EchoParamsTest, TestComponentsName, TestAuthorizationFramework, DebugComponentTest, ChangedSchemaMergeTest, InfoHandlerTest, TestSolrJ, ExplicitHLLTest, AutoCommitTest, TestConfigSetsAPIExclusivity, TestLeaderElectionZkExpiry, HdfsTlogReplayBufferedWhileIndexingTest, LeaderFailoverAfterPartitionTest, SuggesterTSTTest, PrimUtilsTest, TestLockTree, TestManagedSchema, SuggesterWFSTTest, CurrencyFieldXmlFileTest, HLLUtilTest, DistributedQueueTest, TestSQLHandlerNonCloud, TestQuerySenderNoQuery, TestUseDocValuesAsStored, TestTolerantSearch, NoCacheHeaderTest, BlockJoinFacetRandomTest, SynonymTokenizerTest, ZkStateWriterTest, TestFileDictionaryLookup, RequestHandlersTest, TestPHPSerializedResponseWriter, TestMergePolicyConfig, AnalysisErrorHandlingTest, LukeRequestHandlerTest, DeleteReplicaTest, TestSegmentSorting, TestBinaryResponseWriter, AnalyticsQueryTest, TestSolrDeletionPolicy1, TestCoreDiscovery, CoreAdminHandlerTest, TestExactSharedStatsCache, TestDistributedMissingSort, CachingDirectoryFactoryTest, TestQueryWrapperFilter, TestSolrCoreSnapshots, SpatialRPTFieldTypeTest, SolrGraphiteReporterTest, TestTolerantUpdateProcessorRandomCloud, TestStandardQParsers, TestFieldResource, ExitableDirectoryReaderTest, TestWriterPerf, TestReRankQParserPlugin, DistribCursorPagingTest, SliceStateTest, OutputWriterTest, SSLMigrationTest, TestSolrDynamicMBean, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestReplicationHandler]
[junit4] Completed [356/680 (1!)] on J2 in 95.52s, 15 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 63443 lines...]