You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/10/12 19:14:28 UTC

[JENKINS] Lucene-Solr-7.x-Linux (32bit/jdk1.8.0_144) - Build # 603 - Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/603/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseG1GC

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([65E8B336A3F734D0:71A0E86380F089CE]: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 12240 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> 730155 INFO  (SUITE-TestReplicationHandler-seed#[65E8B336A3F734D0]-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-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/init-core-data-001
   [junit4]   2> 730156 INFO  (SUITE-TestReplicationHandler-seed#[65E8B336A3F734D0]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 730156 INFO  (SUITE-TestReplicationHandler-seed#[65E8B336A3F734D0]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 730157 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater
   [junit4]   2> 730158 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/collection1
   [junit4]   2> 730160 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 730160 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b721ff{/solr,null,AVAILABLE}
   [junit4]   2> 730165 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@6d658a{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
   [junit4]   2> 730165 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server Started @??????ms
   [junit4]   2> 730165 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=43711}
   [junit4]   2> 730165 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 730165 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 730165 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 730165 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 730165 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-12T18:28:26.126Z
   [junit4]   2> 730166 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/solr.xml
   [junit4]   2> 730167 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 730168 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 730177 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 730201 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730208 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730208 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730209 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/.
   [junit4]   2> 730209 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 730210 INFO  (coreLoadExecutor-2208-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 730221 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 730232 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 730233 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 730233 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/./collection1, trusted=true
   [junit4]   2> 730233 INFO  (coreLoadExecutor-2208-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@413c42
   [junit4]   2> 730233 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 730233 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 730234 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=31672691, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 730268 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 730268 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 730269 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=61.8818359375, floorSegmentMB=0.3125, forceMergeDeletesPctAllowed=29.64669566767733, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1498035153690883
   [junit4]   2> 730269 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@8d96f8[collection1] main]
   [junit4]   2> 730269 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-001/collection1/conf
   [junit4]   2> 730270 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 730270 INFO  (coreLoadExecutor-2208-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 730270 INFO  (searcherExecutor-2209-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8d96f8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 730271 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/collection1
   [junit4]   2> 730273 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 730273 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d9e0c6{/solr,null,AVAILABLE}
   [junit4]   2> 730274 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@e94a9e{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
   [junit4]   2> 730274 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server Started @??????ms
   [junit4]   2> 730274 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=40769}
   [junit4]   2> 730274 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 730274 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 730274 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 730274 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 730274 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-12T18:28:26.235Z
   [junit4]   2> 730275 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/solr.xml
   [junit4]   2> 730278 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 730278 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 730279 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 730309 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730316 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730317 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730318 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/.
   [junit4]   2> 730318 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 730318 INFO  (coreLoadExecutor-2218-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 730327 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 730332 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 730333 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 730333 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/./collection1, trusted=true
   [junit4]   2> 730334 INFO  (coreLoadExecutor-2218-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@413c42
   [junit4]   2> 730334 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 730334 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 730335 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=31672691, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 730361 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 730361 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 730362 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=61.8818359375, floorSegmentMB=0.3125, forceMergeDeletesPctAllowed=29.64669566767733, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1498035153690883
   [junit4]   2> 730362 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@b5451b[collection1] main]
   [junit4]   2> 730362 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/collection1/conf
   [junit4]   2> 730363 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
   [junit4]   2> 730363 INFO  (coreLoadExecutor-2218-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 730364 INFO  (searcherExecutor-2219-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@b5451b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 730372 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@e94a9e{HTTP/1.1,[http/1.1]}{127.0.0.1:?}
   [junit4]   2> 730372 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=17576603
   [junit4]   2> 730372 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 730372 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@87d976: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1642723
   [junit4]   2> 730377 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 730378 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1afa28e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1be49f4
   [junit4]   2> 730381 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 730381 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e57996: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@181cbdb
   [junit4]   2> 730382 INFO  (coreCloseExecutor-2224-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1e26faa
   [junit4]   2> 730382 INFO  (coreCloseExecutor-2224-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=31616938
   [junit4]   2> 730382 INFO  (coreCloseExecutor-2224-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e1b22e: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@9ddee8
   [junit4]   2> 730394 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1d9e0c6{/solr,null,UNAVAILABLE}
   [junit4]   2> 730394 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 730395 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1501f4f{/solr,null,AVAILABLE}
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@13497d3{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server Started @??????ms
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=36269}
   [junit4]   2> 730396 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-12T18:28:26.357Z
   [junit4]   2> 730396 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/solr.xml
   [junit4]   2> 730398 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 730398 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 730399 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 730421 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730428 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730428 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730430 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/.
   [junit4]   2> 730430 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 730430 INFO  (coreLoadExecutor-2231-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 730436 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 730440 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 730442 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 730442 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/./collection1, trusted=true
   [junit4]   2> 730442 INFO  (coreLoadExecutor-2231-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@413c42
   [junit4]   2> 730442 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 730442 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 730444 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=31672691, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 730467 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 730467 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 730468 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=61.8818359375, floorSegmentMB=0.3125, forceMergeDeletesPctAllowed=29.64669566767733, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1498035153690883
   [junit4]   2> 730468 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d1645e[collection1] main]
   [junit4]   2> 730468 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-002/collection1/conf
   [junit4]   2> 730469 INFO  (coreLoadExecutor-2231-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 730470 INFO  (searcherExecutor-2232-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d1645e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 730470 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/collection1
   [junit4]   2> 730472 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 730473 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2eafda{/solr,null,AVAILABLE}
   [junit4]   2> 730473 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@147d042{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
   [junit4]   2> 730473 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.Server Started @??????ms
   [junit4]   2> 730473 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=34793}
   [junit4]   2> 730474 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 730474 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 730474 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 730474 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 730474 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-10-12T18:28:26.435Z
   [junit4]   2> 730474 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/solr.xml
   [junit4]   2> 730476 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 730476 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 730477 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 730501 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730508 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730508 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@413c42
   [junit4]   2> 730510 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/.
   [junit4]   2> 730510 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 730510 INFO  (coreLoadExecutor-2241-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 730518 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 730522 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 730523 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 730523 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/./collection1, trusted=true
   [junit4]   2> 730523 INFO  (coreLoadExecutor-2241-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@413c42
   [junit4]   2> 730523 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 730523 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/./collection1/data/]
   [junit4]   2> 730524 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=31672691, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 730548 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 730548 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 730549 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=61.8818359375, floorSegmentMB=0.3125, forceMergeDeletesPctAllowed=29.64669566767733, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1498035153690883
   [junit4]   2> 730549 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c51c86[collection1] main]
   [junit4]   2> 730549 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001/solr-instance-003/collection1/conf
   [junit4]   2> 730550 WARN  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 730551 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler  No value set for 'pollInterval'. Timer Task not started.
   [junit4]   2> 730551 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
   [junit4]   2> 730551 INFO  (coreLoadExecutor-2241-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 730552 INFO  (searcherExecutor-2242-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c51c86[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 730555 INFO  (qtp15796803-8618) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1
   [junit4]   2> 730555 INFO  (qtp15796803-8619) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 730556 INFO  (qtp15796803-8620) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 730556 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 730556 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@cd6912 commitCommandVersion:0
   [junit4]   2> 730558 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d4c794[collection1] main]
   [junit4]   2> 730558 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 730559 INFO  (searcherExecutor-2209-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d4c794[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C3)))}
   [junit4]   2> 730559 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 730560 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 730562 INFO  (qtp15796803-8619) [    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> 730562 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 730562 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507832906517
   [junit4]   2> 730562 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 730562 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 730562 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 730563 INFO  (qtp15796803-8620) [    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> 730563 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 730565 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=53.296875, floorSegmentMB=1.0830078125, forceMergeDeletesPctAllowed=24.303923321982673, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5829853508709046
   [junit4]   2> 730565 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 730566 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@59f124 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@127804)
   [junit4]   2> 730566 INFO  (qtp15796803-8613) [    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> 730567 INFO  (qtp15796803-8614) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730568 INFO  (qtp15796803-8619) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730568 INFO  (qtp15796803-8620) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730569 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730569 INFO  (qtp15796803-8614) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730569 INFO  (qtp15796803-8619) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730570 INFO  (qtp15796803-8620) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730570 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730571 INFO  (qtp15796803-8614) [    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> 730571 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1888) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@59f124 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@127804)
   [junit4]   2> 730572 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 730572 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 730573 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f86af[collection1] main]
   [junit4]   2> 730574 INFO  (searcherExecutor-2242-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4f86af[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C3)))}
   [junit4]   2> 730574 INFO  (qtp25902829-8673) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:43711/solr/collection1/replication&command=fetchindex} status=0 QTime=14
   [junit4]   2> 730575 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
   [junit4]   2> 730575 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
   [junit4]   2> 730576 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 730577 INFO  (qtp25902829-8673) [    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> 730577 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 730577 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507832906517
   [junit4]   2> 730577 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 730577 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 730577 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 730578 INFO  (qtp25902829-8669) [    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=0
   [junit4]   2> 730578 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 730579 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=53.296875, floorSegmentMB=1.0830078125, forceMergeDeletesPctAllowed=24.303923321982673, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5829853508709046
   [junit4]   2> 730579 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 730579 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1f03b3d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@540814)
   [junit4]   2> 730580 INFO  (qtp25902829-8675) [    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> 730580 INFO  (qtp25902829-8670) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730581 INFO  (qtp25902829-8673) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730582 INFO  (qtp25902829-8669) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730582 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730583 INFO  (qtp25902829-8670) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730583 INFO  (qtp25902829-8673) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730584 INFO  (qtp25902829-8669) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730584 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730598 INFO  (qtp25902829-8670) [    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> 730598 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1888) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1f03b3d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@540814)
   [junit4]   2> 730599 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 730599 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 730599 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@eb34ba[collection1] main]
   [junit4]   2> 730600 INFO  (searcherExecutor-2232-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@eb34ba[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C3)))}
   [junit4]   2> 730600 INFO  (qtp6901781-8656) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:34793/solr/collection1/replication&command=fetchindex} status=0 QTime=24
   [junit4]   2> 730601 INFO  (qtp6901781-8658) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
   [junit4]   2> 730601 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
   [junit4]   2> 730602 INFO  (qtp15796803-8618) [    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> 730602 INFO  (qtp15796803-8620) [    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> 730604 WARN  (qtp25902829-8668) [    x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master 
   [junit4]   2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:TEST_PORT/solr: Expected mime type application/octet-stream but got text/html. <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
   [junit4]   2> <html><head>
   [junit4]   2> <title>404 Not Found</title>
   [junit4]   2> </head><body>
   [junit4]   2> <h1>Not Found</h1>
   [junit4]   2> <p>The requested URL /solr/replication was not found on this server.</p>
   [junit4]   2> <hr>
   [junit4]   2> <address>Apache/2.4.18 (Ubuntu) Server at 127.0.0.1 Port 80</address>
   [junit4]   2> </body></html>
   [junit4]   2> 
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
   [junit4]   2> 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]   2> 	at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1823)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:954)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:332)
   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2484)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:720)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:526)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 730604 INFO  (qtp25902829-8668) [    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> 730605 INFO  (qtp15796803-8613) [    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> 730605 INFO  (qtp25902829-8669) [    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> 730606 WARN  (qtp25902829-8675) [    x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master 
   [junit4]   2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:TEST_PORT/solr: Expected mime type application/octet-stream but got text/html. <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
   [junit4]   2> <html><head>
   [junit4]   2> <title>404 Not Found</title>
   [junit4]   2> </head><body>
   [junit4]   2> <h1>Not Found</h1>
   [junit4]   2> <p>The requested URL /solr/replication was not found on this server.</p>
   [junit4]   2> <hr>
   [junit4]   2> <address>Apache/2.4.18 (Ubuntu) Server at 127.0.0.1 Port 80</address>
   [junit4]   2> </body></html>
   [junit4]   2> 
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
   [junit4]   2> 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]   2> 	at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1823)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:954)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:332)
   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2484)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:720)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:526)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 730606 INFO  (qtp25902829-8675) [    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> 730607 WARN  (qtp25902829-8670) [    x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master 
   [junit4]   2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:TEST_PORT/solr: Expected mime type application/octet-stream but got text/html. <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
   [junit4]   2> <html><head>
   [junit4]   2> <title>404 Not Found</title>
   [junit4]   2> </head><body>
   [junit4]   2> <h1>Not Found</h1>
   [junit4]   2> <p>The requested URL /solr/replication was not found on this server.</p>
   [junit4]   2> <hr>
   [junit4]   2> <address>Apache/2.4.18 (Ubuntu) Server at 127.0.0.1 Port 80</address>
   [junit4]   2> </body></html>
   [junit4]   2> 
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
   [junit4]   2> 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]   2> 	at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1823)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:954)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:332)
   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2484)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:720)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:526)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 730608 INFO  (qtp25902829-8670) [    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> 730609 INFO  (qtp25902829-8673) [    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> 730609 INFO  (qtp6901781-8651) [    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> 730609 INFO  (qtp25902829-8668) [    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> 730610 INFO  (qtp6901781-8656) [    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> 730610 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
   [junit4]   2> 730610 INFO  (qtp25902829-8670) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 730611 INFO  (qtp25902829-8673) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 730611 INFO  (qtp25902829-8668) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 730611 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 730611 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4aabe2 commitCommandVersion:0
   [junit4]   2> 730613 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1926dd2[collection1] main]
   [junit4]   2> 730613 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 730614 INFO  (searcherExecutor-2242-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1926dd2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.2.0):C4)))}
   [junit4]   2> 730614 INFO  (qtp25902829-8675) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 730615 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 730615 INFO  (qtp15796803-8619) [    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> 730615 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 730615 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507832906517
   [junit4]   2> 730615 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 730615 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507832906572
   [junit4]   2> 730615 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 730616 INFO  (qtp15796803-8618) [    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=0
   [junit4]   2> 730616 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 730616 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@1f4bb4b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@128ccfa)
   [junit4]   2> 730617 INFO  (qtp15796803-8620) [    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> 730617 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730618 INFO  (qtp15796803-8619) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730618 INFO  (qtp15796803-8618) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730619 INFO  (qtp15796803-8620) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730619 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730620 INFO  (qtp15796803-8619) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730620 INFO  (qtp15796803-8618) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730621 INFO  (qtp15796803-8620) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730621 INFO  (qtp15796803-8613) [    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> 730622 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=1888) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1f4bb4b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@128ccfa)
   [junit4]   2> 730622 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.c.SolrCore Updating index properties... index=index.20171013032826577
   [junit4]   2> 730622 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(RAMDirectory@97e979 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1533160)
   [junit4]   2> 730623 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=53.296875, floorSegmentMB=1.0830078125, forceMergeDeletesPctAllowed=24.303923321982673, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5829853508709046
   [junit4]   2> 730623 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 730623 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c347aa[collection1] main]
   [junit4]   2> 730624 INFO  (searcherExecutor-2242-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c347aa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C3)))}
   [junit4]   2> 730624 INFO  (qtp25902829-8674) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:43711/solr/collection1/replication&command=fetchindex} status=0 QTime=9
   [junit4]   2> 730625 INFO  (qtp25902829-8670) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
   [junit4]   2> 730625 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
   [junit4]   2> 730626 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 730626 INFO  (qtp25902829-8669) [    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> 730626 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 730626 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507832906517
   [junit4]   2> 730626 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 730626 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507832906517
   [junit4]   2> 730626 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 730627 INFO  (qtp6901781-8657) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:34793/solr/collection1/replication&command=fetchindex} status=0 QTime=0
   [junit4]   2> 730627 INFO  (qtp6901781-8652) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
   [junit4]   2> 730627 INFO  (TEST-TestReplicationHandler.doTestRepeater-seed#[65E8B336A3F734D0]) [    ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
   [junit4]   2> 730628 INFO  (qtp15796803-8614) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 730628 INFO  (qtp15796803-8618) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 730629 INFO  (qtp15796803-8620) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
   [junit4]   2> 730629 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 730629 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@cd6912 commitCommandVersion:0
   [junit4]   2> 730630 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@8a3f8c[collection1] main]
   [junit4]   2> 730630 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 730631 INFO  (searcherExecutor-2209-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8a3f8c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C3) Uninverting(_1(7.2.0):C3)))}
   [junit4]   2> 730631 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 730632 WARN  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 730633 INFO  (qtp15796803-8619) [    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> 730633 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 730633 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 1507832906590
   [junit4]   2> 730633 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 730633 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Slave's version: 1507832906517
   [junit4]   2> 730633 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 730633 INFO  (qtp15796803-8618) [    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> 730634 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 19
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=53.296875, floorSegmentMB=1.0830078125, forceMergeDeletesPctAllowed=24.303923321982673, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5829853508709046
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@4e3ba4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@be09c1)
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tib because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tiv because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.doc because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 730635 INFO  (explicit-fetchindex-cmd) [    x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 730636 INFO  (qtp15796803-8620) [    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> 730637 INFO  (qtp15796803-8613) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
   [junit4]   2> 730637 INFO  (qtp15796803-8619) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication p

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

se.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 809130 INFO  (qtp11517973-9750) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={tlogFile=../dir/traversal&command=filecontent} status=403 QTime=0
   [junit4]   2> 809131 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.SolrTestCaseJ4 ###Ending doTestIllegalFilePaths
   [junit4]   2> 809131 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@127484d{HTTP/1.1,[http/1.1]}{127.0.0.1:?}
   [junit4]   2> 809131 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1823217
   [junit4]   2> 809131 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 809131 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52621a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1a9d5c6
   [junit4]   2> 809136 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 809136 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@28a846: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1be49f4
   [junit4]   2> 809139 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 809139 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ddd678: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@181cbdb
   [junit4]   2> 809139 INFO  (coreCloseExecutor-2885-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1308284
   [junit4]   2> 809139 INFO  (coreCloseExecutor-2885-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=19956356
   [junit4]   2> 809139 INFO  (coreCloseExecutor-2885-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fe16a: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@77326d
   [junit4]   2> 809144 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@10e9340{/solr,null,UNAVAILABLE}
   [junit4]   2> 809144 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@1319a3b{HTTP/1.1,[http/1.1]}{127.0.0.1:?}
   [junit4]   2> 809144 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=3184341
   [junit4]   2> 809144 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 809144 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ca1f6a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1d8eaed
   [junit4]   2> 809149 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 809149 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@85ec0d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1be49f4
   [junit4]   2> 809153 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 809153 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d644b: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@181cbdb
   [junit4]   2> 809153 INFO  (coreCloseExecutor-2887-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1c04d2
   [junit4]   2> 809153 INFO  (coreCloseExecutor-2887-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1836242
   [junit4]   2> 809153 INFO  (coreCloseExecutor-2887-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@49ea65: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@118a1a1
   [junit4]   2> 809159 INFO  (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[65E8B336A3F734D0]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c0d833{/solr,null,UNAVAILABLE}
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_65E8B336A3F734D0-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {name=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneVarGapFixedInterval), newname=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, maxPointsInLeafNode=971, maxMBSortInHeap=7.4303923321982674, sim=RandomSimilarity(queryNorm=true): {}, locale=th-TH-u-nu-thai-x-lvariant-TH, timezone=Etc/GMT-9
   [junit4]   2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=369541560,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestShardHandlerFactory, TestManagedResourceStorage, SparseHLLTest, RankQueryTest, TestCSVLoader, CdcrUpdateLogTest, TestFunctionQuery, TestJsonRequest, TestConfigOverlay, DistributedFacetPivotLongTailTest, SynonymTokenizerTest, ChaosMonkeyNothingIsSafeTest, TestPolicyCloud, HdfsBasicDistributedZkTest, PreAnalyzedFieldManagedSchemaCloudTest, TestLegacyFieldCache, TestPayloadCheckQParserPlugin, MoveReplicaHDFSTest, BlockCacheTest, TestFieldCache, PluginInfoTest, ConcurrentDeleteAndCreateCollectionTest, TestStreamBody, TestPhraseSuggestions, ChaosMonkeySafeLeaderWithPullReplicasTest, TestConfigSetsAPIZkFailure, TestWordDelimiterFilterFactory, DistributedExpandComponentTest, HdfsDirectoryFactoryTest, SSLMigrationTest, TestHdfsUpdateLog, TestSolrQueryResponse, DocValuesMissingTest, TestSolrQueryParser, TestLegacyNumericRangeQueryBuilder, HdfsDirectoryTest, TestSegmentSorting, BasicZkTest, TestGraphTermsQParserPlugin, TestUninvertingReader, WrapperMergePolicyFactoryTest, JSONWriterTest, TestRecoveryHdfs, TestRequestForwarding, CoreSorterTest, UpdateParamsTest, TestRandomDVFaceting, SolrPluginUtilsTest, TestMiniSolrCloudClusterSSL, SolrCloudExampleTest, MBeansHandlerTest, AtomicUpdateProcessorFactoryTest, UpdateLogTest, DocExpirationUpdateProcessorFactoryTest, TestUseDocValuesAsStored2, RuleEngineTest, SpellCheckCollatorWithCollapseTest, TestCloudDeleteByQuery, BinaryUpdateRequestHandlerTest, TestIndexingPerformance, SolrGraphiteReporterTest, TestSolrDeletionPolicy1, NodeMutatorTest, HttpPartitionTest, TestLMDirichletSimilarityFactory, TestClassicSimilarityFactory, TestQuerySenderNoQuery, TestCustomSort, TestMultiValuedNumericRangeQuery, BlockJoinFacetSimpleTest, QueryElevationComponentTest, BigEndianAscendingWordSerializerTest, TestMultiWordSynonyms, TestSimpleQParserPlugin, SpellCheckCollatorTest, TestLegacyFieldReuse, FieldMutatingUpdateProcessorTest, TestCollationField, ZkNodePropsTest, TestReplicationHandler]
   [junit4] Completed [223/744 (1!)] on J0 in 79.01s, 16 tests, 1 error, 1 skipped <<< FAILURES!

[...truncated 44529 lines...]