lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_144) - Build # 20635 - Still Failing!
Date Sun, 08 Oct 2017 23:03:41 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20635/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication

Error Message:
Index: 0, Size: 0

Stack Trace:
java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
	at __randomizedtesting.SeedInfo.seed([8D7F1A5A5AD488DA:9937410F79D335C4]:0)
	at java.util.ArrayList.rangeCheck(ArrayList.java:653)
	at java.util.ArrayList.get(ArrayList.java:429)
	at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561)
	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:1737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
	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:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
	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:748)




Build Log:
[...truncated 12395 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> 1254608 INFO  (SUITE-TestReplicationHandler-seed#[8D7F1A5A5AD488DA]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/init-core-data-001
   [junit4]   2> 1254609 WARN  (SUITE-TestReplicationHandler-seed#[8D7F1A5A5AD488DA]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=123 numCloses=123
   [junit4]   2> 1254609 INFO  (SUITE-TestReplicationHandler-seed#[8D7F1A5A5AD488DA]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1254611 INFO  (SUITE-TestReplicationHandler-seed#[8D7F1A5A5AD488DA]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 1254614 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
   [junit4]   2> 1254614 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/collection1
   [junit4]   2> 1254618 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1254619 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@fbe952{/solr,null,AVAILABLE}
   [junit4]   2> 1254620 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@3484c{HTTP/1.1,[http/1.1]}{127.0.0.1:33399}
   [junit4]   2> 1254620 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server Started @1256140ms
   [junit4]   2> 1254620 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=33399}
   [junit4]   2> 1254620 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1254620 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 1254620 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1254620 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1254620 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-08T22:23:14.972Z
   [junit4]   2> 1254621 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/solr.xml
   [junit4]   2> 1254628 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1254628 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1254630 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1254676 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254697 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254697 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254699 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/.
   [junit4]   2> 1254699 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1254699 INFO  (coreLoadExecutor-4744-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1254713 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1254723 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1254726 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1254726 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/./collection1, trusted=true
   [junit4]   2> 1254727 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254727 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1254727 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 1254730 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=34.529296875, floorSegmentMB=1.4404296875, forceMergeDeletesPctAllowed=7.656717482573979, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1254774 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1254774 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1254775 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4127076725202249]
   [junit4]   2> 1254776 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15a624c[collection1] main]
   [junit4]   2> 1254776 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-001/collection1/conf
   [junit4]   2> 1254777 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1254777 INFO  (coreLoadExecutor-4744-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1254788 INFO  (searcherExecutor-4745-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15a624c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1254789 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/collection1
   [junit4]   2> 1254792 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1254793 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c0d4d{/solr,null,AVAILABLE}
   [junit4]   2> 1254793 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@13bed3{HTTP/1.1,[http/1.1]}{127.0.0.1:43999}
   [junit4]   2> 1254794 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server Started @1256314ms
   [junit4]   2> 1254794 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=43999}
   [junit4]   2> 1254794 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1254794 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 1254794 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1254794 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1254794 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-08T22:23:15.146Z
   [junit4]   2> 1254795 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/solr.xml
   [junit4]   2> 1254799 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1254800 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1254801 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1254850 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254862 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254862 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254864 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/.
   [junit4]   2> 1254864 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1254865 INFO  (coreLoadExecutor-4754-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1254874 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1254880 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1254887 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1254887 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/./collection1, trusted=true
   [junit4]   2> 1254887 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1254888 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1254888 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 1254890 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=34.529296875, floorSegmentMB=1.4404296875, forceMergeDeletesPctAllowed=7.656717482573979, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1254940 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1254940 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1254942 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4127076725202249]
   [junit4]   2> 1254942 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c34719[collection1] main]
   [junit4]   2> 1254942 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-002/collection1/conf
   [junit4]   2> 1254945 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
   [junit4]   2> 1254945 INFO  (coreLoadExecutor-4754-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1254946 INFO  (qtp12908391-13362) [    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> 1254947 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 1254947 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 1254947 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1254947 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 1254947 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine...
   [junit4]   2> 1254947 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1254947 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@168fdf commitCommandVersion:0
   [junit4]   2> 1254948 INFO  (qtp12908391-13358) [    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> 1254948 INFO  (searcherExecutor-4755-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c34719[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1254950 INFO  (qtp12908391-13358) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 1254950 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@fc8b7f[collection1] main]
   [junit4]   2> 1254950 INFO  (indexFetcher-4759-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1254951 INFO  (qtp12908391-13357) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1254951 INFO  (qtp12908391-13357) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@10821d commitCommandVersion:0
   [junit4]   2> 1254953 INFO  (searcherExecutor-4755-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@fc8b7f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1254954 INFO  (qtp12908391-13357) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@46934[collection1] main]
   [junit4]   2> 1254954 INFO  (qtp12908391-13357) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1254956 INFO  (searcherExecutor-4745-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@46934[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))}
   [junit4]   2> 1254956 INFO  (qtp12908391-13357) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 6
   [junit4]   2> 1254959 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 1254960 INFO  (qtp12908391-13363) [    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> 1254961 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1254961 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507501395303
   [junit4]   2> 1254961 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 1254961 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507501395299
   [junit4]   2> 1254961 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1254963 INFO  (qtp12908391-13363) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
   [junit4]   2> 1254964 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
   [junit4]   2> 1254964 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@438752 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc16e4)
   [junit4]   2> 1254965 INFO  (qtp12908391-13358) [    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> 1254967 INFO  (qtp12908391-13357) [    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> 1254969 INFO  (qtp12908391-13364) [    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> 1254969 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher File segments_2 did not match. expected checksum is 1660953221 and actual is checksum 718855795. expected length is 185 and actual length is 120
   [junit4]   2> 1254970 INFO  (qtp12908391-13363) [    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> 1254971 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=1937) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@438752 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fc16e4)
   [junit4]   2> 1254972 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.c.SolrCore Updating index properties... index=index.20171008172315316
   [junit4]   2> 1254972 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(RAMDirectory@1da64e3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34cfc9)
   [junit4]   2> 1254973 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=32.1923828125, floorSegmentMB=1.9208984375, forceMergeDeletesPctAllowed=4.569729171002614, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3933583475618263
   [junit4]   2> 1254973 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1254974 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a8f33[collection1] main]
   [junit4]   2> 1254976 INFO  (searcherExecutor-4755-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a8f33[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))}
   [junit4]   2> 1254976 INFO  (qtp11652923-13379) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:33399/solr/collection1/replication&command=fetchindex} status=0 QTime=17
   [junit4]   2> 1254979 INFO  (qtp11652923-13375) [    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=0
   [junit4]   2> 1254979 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 1254980 INFO  (qtp12908391-13362) [    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> 1254981 INFO  (qtp12908391-13357) [    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> 1254983 INFO  (qtp12908391-13358) [    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> 1254983 INFO  (qtp11652923-13381) [    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> 1254984 INFO  (qtp12908391-13364) [    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> 1254985 INFO  (qtp11652923-13374) [    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> 1254985 INFO  (qtp12908391-13362) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1254985 INFO  (qtp12908391-13362) [    x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1254985 INFO  (qtp12908391-13362) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1254985 INFO  (qtp12908391-13362) [    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> 1254986 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 1254988 INFO  (qtp12908391-13363) [    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> 1254988 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1254988 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507501395303
   [junit4]   2> 1254988 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 1254989 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507501395303
   [junit4]   2> 1254989 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 1254989 INFO  (qtp11652923-13380) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:33399/solr/collection1/replication&command=fetchindex} status=0 QTime=2
   [junit4]   2> 1254991 INFO  (qtp12908391-13362) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 1
   [junit4]   2> 1254991 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1254991 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@10821d commitCommandVersion:0
   [junit4]   2> 1254994 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@503576[collection1] main]
   [junit4]   2> 1254995 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1254999 INFO  (searcherExecutor-4745-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@503576[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1)))}
   [junit4]   2> 1254999 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 7
   [junit4]   2> 1255000 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 1255002 INFO  (qtp12908391-13358) [    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> 1255002 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 1255002 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507501395343
   [junit4]   2> 1255002 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 1255002 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507501395303
   [junit4]   2> 1255002 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1255003 INFO  (qtp12908391-13363) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
   [junit4]   2> 1255004 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7
   [junit4]   2> 1255005 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=32.1923828125, floorSegmentMB=1.9208984375, forceMergeDeletesPctAllowed=4.569729171002614, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3933583475618263
   [junit4]   2> 1255005 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1255006 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@458c89 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194c32d)
   [junit4]   2> 1255006 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
   [junit4]   2> 1255006 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1255006 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
   [junit4]   2> 1255007 INFO  (qtp12908391-13362) [    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> 1255008 INFO  (qtp12908391-13364) [    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> 1255010 INFO  (qtp12908391-13358) [    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> 1255011 INFO  (qtp12908391-13363) [    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> 1255012 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1999) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@458c89 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194c32d)
   [junit4]   2> 1255013 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=55.4951171875, floorSegmentMB=1.2626953125, forceMergeDeletesPctAllowed=12.304028231000943, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1255014 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1255015 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7184d5[collection1] main]
   [junit4]   2> 1255017 INFO  (searcherExecutor-4755-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7184d5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1)))}
   [junit4]   2> 1255017 INFO  (qtp11652923-13375) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:33399/solr/collection1/replication&command=fetchindex} status=0 QTime=17
   [junit4]   2> 1255019 INFO  (qtp11652923-13379) [    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> 1255020 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 1255020 INFO  (qtp12908391-13357) [    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> 1255021 INFO  (qtp12908391-13364) [    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> 1255022 INFO  (qtp12908391-13362) [    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> 1255022 INFO  (qtp11652923-13374) [    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> 1255023 INFO  (qtp12908391-13358) [    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> 1255024 INFO  (qtp11652923-13380) [    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> 1255025 INFO  (qtp12908391-13357) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 1255026 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1255026 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@10821d commitCommandVersion:0
   [junit4]   2> 1255028 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@66df98[collection1] realtime]
   [junit4]   2> 1255028 INFO  (qtp12908391-13364) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1255029 INFO  (qtp12908391-13364) [    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> 1255030 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 1255031 INFO  (qtp12908391-13363) [    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> 1255032 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 4
   [junit4]   2> 1255032 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507501395378
   [junit4]   2> 1255032 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 1255032 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507501395343
   [junit4]   2> 1255032 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1255033 INFO  (qtp12908391-13358) [    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> 1255033 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 1255035 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=32.1923828125, floorSegmentMB=1.9208984375, forceMergeDeletesPctAllowed=4.569729171002614, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3933583475618263
   [junit4]   2> 1255035 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1255036 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1255036 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> 1256036 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1256036 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> 1257042 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1257042 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> 1258042 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1258042 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> 1259043 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1259043 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> 1260043 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1260043 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> 1261043 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1261043 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> 1262044 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1262044 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> 1263044 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1263044 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> 1264044 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1264044 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> 1265048 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1265051 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> 1266063 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> 1266063 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1a4de83 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1bc6d6)
   [junit4]   2> 1266067 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
   [junit4]   2> 1266067 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1266067 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
   [junit4]   2> 1266067 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
   [junit4]   2> 1266067 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
   [junit4]   2> 1266067 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 1266070 INFO  (qtp12908391-13357) [    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> 1266124 INFO  (qtp12908391-13364) [    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> 1266126 INFO  (qtp12908391-13363) [    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> 1266127 INFO  (qtp12908391-13358) [    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> 1266128 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2061) : 11 secs (187 bytes/sec) to MockDirectoryWrapper(RAMDirectory@1a4de83 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1bc6d6)
   [junit4]   2> 1266129 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=55.4951171875, floorSegmentMB=1.2626953125, forceMergeDeletesPctAllowed=12.304028231000943, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1266129 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1266131 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@53a90a[collection1] main]
   [junit4]   2> 1266132 INFO  (searcherExecutor-4755-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@53a90a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1)))}
   [junit4]   2> 1266133 INFO  (qtp11652923-13381) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:33399/solr/collection1/replication&command=fetchindex} status=0 QTime=11102
   [junit4]   2> 1266135 INFO  (qtp12908391-13362) [    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> 1266138 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
   [junit4]   2> 1266141 INFO  (qtp11652923-13375) [    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=1
   [junit4]   2> 1266142 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 1266144 INFO  (qtp12908391-13362) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 1
   [junit4]   2> 1266145 INFO  (qtp12908391-13363) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1266145 INFO  (qtp12908391-13363) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@10821d commitCommandVersion:0
   [junit4]   2> 1266148 INFO  (qtp12908391-13363) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@162d2d8[collection1] main]
   [junit4]   2> 1266149 INFO  (qtp12908391-13363) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1266151 INFO  (searcherExecutor-4745-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@162d2d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1) Uninverting(_3(8.0.0):c1)))}
   [junit4]   2> 1266152 INFO  (qtp12908391-13363) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 6
   [junit4]   2> 1266158 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 1266160 INFO  (qtp12908391-13357) [    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> 1266161 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 5
   [junit4]   2> 1266161 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507501406497
   [junit4]   2> 1266161 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4
   [junit4]   2> 1266161 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507501395378
   [junit4]   2> 1266161 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1266167 INFO  (qtp12908391-13364) [    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=0
   [junit4]   2> 1266168 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 13
   [junit4]   2> 1266170 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=32.1923828125, floorSegmentMB=1.9208984375, forceMergeDeletesPctAllowed=4.569729171002614, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3933583475618263
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@ee9e20 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20952)
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.cfe because it already exists
   [junit4]   2> 1266171 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.cfs because it already exists
   [junit4]   2> 1266173 INFO  (qtp12908391-13362) [    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> 1266187 INFO  (qtp12908391-13363) [    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> 1266189 INFO  (qtp12908391-13357) [    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> 1266191 INFO  (qtp12908391-13364) [    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> 1266192 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2123) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@ee9e20 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20952)
   [junit4]   2> 1266193 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=55.4951171875, floorSegmentMB=1.2626953125, forceMergeDeletesPctAllowed=12.304028231000943, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1266194 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1266196 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f20567[collection1] main]
   [junit4]   2> 1266202 INFO  (searcherExecutor-4755-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f20567[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1) Uninverting(_3(8.0.0):c1)))}
   [junit4]   2> 1266202 INFO  (qtp11652923-13379) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:33399/solr/collection1/replication&command=fetchindex} status=0 QTime=48
   [junit4]   2> 1266205 INFO  (qtp12908391-13358) [    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> 1266205 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 1266207 INFO  (qtp11652923-13375) [    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> 1266207 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 1266208 INFO  (qtp12908391-13358) [    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> 1266211 INFO  (qtp12908391-13357) [    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> 1266212 INFO  (qtp12908391-13364) [    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> 1266213 INFO  (qtp11652923-13381) [    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> 1266214 INFO  (qtp12908391-13362) [    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> 1266218 INFO  (qtp11652923-13374) [    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> 1266219 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
   [junit4]   2> 1266221 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@3484c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1266221 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=23239443
   [junit4]   2> 1266222 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1266222 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@110c349: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7c50da
   [junit4]   2> 1266237 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1266237 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@af9833: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@a7f797
   [junit4]   2> 1266245 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1266245 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6ff0c2: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@13f8c3e
   [junit4]   2> 1266247 INFO  (coreCloseExecutor-4764-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@19d4d78
   [junit4]   2> 1266247 INFO  (coreCloseExecutor-4764-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=27086200
   [junit4]   2> 1266247 INFO  (coreCloseExecutor-4764-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ee7fe: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@c46d40
   [junit4]   2> 1266259 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@fbe952{/solr,null,UNAVAILABLE}
   [junit4]   2> 1266277 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@13bed3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1266278 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=9950833
   [junit4]   2> 1266278 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1266278 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@11e1daa: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1a3d39c
   [junit4]   2> 1266288 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1266289 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c6cd2b: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@a7f797
   [junit4]   2> 1266296 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1266296 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c742c8: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@13f8c3e
   [junit4]   2> 1266303 INFO  (coreCloseExecutor-4766-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@42877c
   [junit4]   2> 1266303 INFO  (coreCloseExecutor-4766-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4360060
   [junit4]   2> 1266303 INFO  (coreCloseExecutor-4766-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a0dbc5: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@a6c55a
   [junit4]   2> 1266353 INFO  (TEST-TestReplicationHandler.testEmptyCommits-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c0d4d{/solr,null,UNAVAILABLE}
   [junit4]   2> 1266358 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartupWithNoActivity
   [junit4]   2> 1266359 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/collection1
   [junit4]   2> 1266362 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1266374 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b01b83{/solr,null,AVAILABLE}
   [junit4]   2> 1266375 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@96a6ad{HTTP/1.1,[http/1.1]}{127.0.0.1:39433}
   [junit4]   2> 1266375 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server Started @1267896ms
   [junit4]   2> 1266375 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=39433}
   [junit4]   2> 1266376 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1266376 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 1266376 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1266376 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1266376 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-08T22:23:26.728Z
   [junit4]   2> 1266376 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/solr.xml
   [junit4]   2> 1266381 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1266381 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1266383 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1266435 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266462 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266462 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266464 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/.
   [junit4]   2> 1266464 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1266464 INFO  (coreLoadExecutor-4773-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1266514 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1266531 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1266533 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1266533 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/./collection1, trusted=true
   [junit4]   2> 1266534 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266534 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1266534 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/./collection1/data/]
   [junit4]   2> 1266536 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=34.529296875, floorSegmentMB=1.4404296875, forceMergeDeletesPctAllowed=7.656717482573979, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1266614 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1266614 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1266615 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4127076725202249]
   [junit4]   2> 1266616 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1512926[collection1] main]
   [junit4]   2> 1266616 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-003/collection1/conf
   [junit4]   2> 1266617 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1266617 INFO  (coreLoadExecutor-4773-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1266618 INFO  (searcherExecutor-4774-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1512926[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1266620 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/collection1
   [junit4]   2> 1266623 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1266624 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13a54f3{/solr,null,AVAILABLE}
   [junit4]   2> 1266624 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1723a0{HTTP/1.1,[http/1.1]}{127.0.0.1:40409}
   [junit4]   2> 1266625 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.Server Started @1268145ms
   [junit4]   2> 1266625 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=40409}
   [junit4]   2> 1266625 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1266625 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 1266625 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1266625 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1266625 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-08T22:23:26.977Z
   [junit4]   2> 1266626 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/solr.xml
   [junit4]   2> 1266660 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1266660 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1266662 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1266760 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266779 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266780 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266783 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/.
   [junit4]   2> 1266783 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1266783 INFO  (coreLoadExecutor-4783-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1266802 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1266811 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1266813 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1266813 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/./collection1, trusted=true
   [junit4]   2> 1266813 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17379a5
   [junit4]   2> 1266813 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1266813 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/./collection1/data/]
   [junit4]   2> 1266845 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=34.529296875, floorSegmentMB=1.4404296875, forceMergeDeletesPctAllowed=7.656717482573979, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1266910 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1266910 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1266912 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4127076725202249]
   [junit4]   2> 1266913 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@10b0def[collection1] main]
   [junit4]   2> 1266913 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001/solr-instance-004/collection1/conf
   [junit4]   2> 1266919 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
   [junit4]   2> 1266919 INFO  (coreLoadExecutor-4783-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1266920 INFO  (searcherExecutor-4784-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10b0def[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1267430 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@1723a0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1267431 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=32025446
   [junit4]   2> 1267433 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1267434 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@bf8f99: rootName = null, domain = 

[...truncated too long message...]

cationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1793efd: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@46bf50
   [junit4]   2> 1331811 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1331811 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1589241: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@a7f797
   [junit4]   2> 1331812 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1331812 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@675afe: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@13f8c3e
   [junit4]   2> 1331814 INFO  (coreCloseExecutor-5429-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@39c366
   [junit4]   2> 1331814 INFO  (coreCloseExecutor-5429-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=3785574
   [junit4]   2> 1331814 INFO  (coreCloseExecutor-5429-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5a9a3f: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@953649
   [junit4]   2> 1331817 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@d723d0{/solr,null,UNAVAILABLE}
   [junit4]   2> 1331818 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@146182{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1331818 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=11035485
   [junit4]   2> 1331819 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1331819 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@140696e: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1c72aeb
   [junit4]   2> 1331820 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1331820 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d05f8f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@a7f797
   [junit4]   2> 1331821 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1331821 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ab2850: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@13f8c3e
   [junit4]   2> 1331821 INFO  (coreCloseExecutor-5431-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@18b0ff4
   [junit4]   2> 1331821 INFO  (coreCloseExecutor-5431-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=25890804
   [junit4]   2> 1331821 INFO  (coreCloseExecutor-5431-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a8dbc5: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2c1606
   [junit4]   2> 1331824 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[8D7F1A5A5AD488DA]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b64b0a{/solr,null,UNAVAILABLE}
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8D7F1A5A5AD488DA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {name=Lucene50(blocksize=128), id=Lucene50(blocksize=128), newname=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=425, maxMBSortInHeap=5.456972917100261, sim=RandomSimilarity(queryNorm=true): {}, locale=sr-BA, timezone=America/North_Dakota/New_Salem
   [junit4]   2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=108288736,total=337969152
   [junit4]   2> NOTE: All tests run in this JVM: [TestRTimerTree, TestNumericTokenStream, ShardRoutingTest, TestConfigOverlay, TestCloudDeleteByQuery, TestMultiWordSynonyms, TestCollationFieldDocValues, TestConfigReload, FileBasedSpellCheckerTest, TestShardHandlerFactory, AutoAddReplicasPlanActionTest, TestConfigSetsAPIZkFailure, TestSkipOverseerOperations, StatelessScriptUpdateProcessorFactoryTest, SchemaVersionSpecificBehaviorTest, PrimUtilsTest, AtomicUpdateProcessorFactoryTest, SpatialFilterTest, HttpSolrCallGetCoreTest, ZkControllerTest, JSONWriterTest, BadComponentTest, CoreMergeIndexesAdminHandlerTest, TestImpersonationWithHadoopAuth, DistanceFunctionTest, TestLRUStatsCache, TestRequestForwarding, DistributedSuggestComponentTest, TestCustomDocTransformer, ChangedSchemaMergeTest, DeleteNodeTest, TestPerFieldSimilarityWithDefaultOverride, ExecutePlanActionTest, TestNestedDocsSort, AlternateDirectoryTest, CursorMarkTest, TestCursorMarkWithoutUniqueKey, AutoscalingHistoryHandlerTest, LukeRequestHandlerTest, TestUpdate, TestRandomRequestDistribution, CacheHeaderTest, ReplicationFactorTest, SliceStateTest, SuggesterTSTTest, TestSchemaSimilarityResource, TestNamedUpdateProcessors, HdfsCollectionsAPIDistributedZkTest, HdfsThreadLeakTest, TestJsonRequest, AsyncCallRequestStatusResponseTest, CustomCollectionTest, TestGraphMLResponseWriter, TestHdfsUpdateLog, DocExpirationUpdateProcessorFactoryTest, TestPullReplica, TestXIncludeConfig, TestUninvertingReader, DirectSolrSpellCheckerTest, TestStressRecovery, DistributedTermsComponentTest, TestLeaderInitiatedRecoveryThread, DOMUtilTest, BasicFunctionalityTest, DistributedExpandComponentTest, HdfsWriteToMultipleCollectionsTest, NodeAddedTriggerTest, TestConfigSets, PreAnalyzedFieldManagedSchemaCloudTest, TestFuzzyAnalyzedSuggestions, DistanceUnitsTest, ReplicaListTransformerTest, DocValuesTest, TestPartialUpdateDeduplication, SolrIndexMetricsTest, SharedFSAutoReplicaFailoverTest, TestCoreContainer, SolrSlf4jReporterTest, CdcrReplicationDistributedZkTest, NoCacheHeaderTest, IndexSchemaTest, TestPerFieldSimilarity, ChaosMonkeySafeLeaderTest, MetricsConfigTest, TestTolerantUpdateProcessorRandomCloud, BJQParserTest, TestFiltering, DistributedFacetPivotLongTailTest, TestSweetSpotSimilarityFactory, TestSearchPerf, TestManagedSynonymGraphFilterFactory, TestLegacyFieldCache, HdfsBasicDistributedZkTest, TestQueryTypes, ClassificationUpdateProcessorIntegrationTest, RankQueryTest, SpellCheckCollatorWithCollapseTest, BlockJoinFacetSimpleTest, TestGraphTermsQParserPlugin, TestBM25SimilarityFactory, TestFieldCache, TestUnifiedSolrHighlighter, ZkSolrClientTest, BigEndianAscendingWordSerializerTest, TestSimpleQParserPlugin, SolrCloudExampleTest, IndexSchemaRuntimeFieldTest, VMParamsZkACLAndCredentialsProvidersTest, PKIAuthenticationIntegrationTest, TestSystemIdResolver, TestSolrXml, CoreSorterTest, HdfsRecoveryZkTest, TestElisionMultitermQuery, RequestLoggingTest, NodeMutatorTest, DeleteStatusTest, TestSubQueryTransformerCrossCore, TestFieldTypeResource, DistributedFacetPivotWhiteBoxTest, SolrGraphiteReporterTest, TestDistribIDF, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
   [junit4] Completed [271/745 (1!)] on J1 in 77.24s, 16 tests, 1 error, 1 skipped <<< FAILURES!

[...truncated 44252 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:826: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:706: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:693: Source checkout is dirty (unversioned/missing files) after running tests!!! Offending files:
* lucene/licenses/morfologik-ukrainian-search-3.7.5.jar.sha1

Total time: 92 minutes 37 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any

Mime
View raw message