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 2019/03/13 14:18:12 UTC
[JENKINS-EA] Lucene-Solr-8.x-Linux (64bit/jdk-13-ea+8) - Build #
258 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/258/
Java: 64bit/jdk-13-ea+8 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication
Error Message:
expected:<1552483939201> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1552483939201> but was:<0>
at __randomizedtesting.SeedInfo.seed([99A217A86175F907:8DEA4CFD42724419]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:118)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.handler.TestReplicationHandler.assertVersions(TestReplicationHandler.java:1091)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:510)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 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.base/java.lang.Thread.run(Thread.java:835)
Build Log:
[...truncated 12828 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 121985 INFO (SUITE-TestReplicationHandler-seed#[99A217A86175F907]-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-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/init-core-data-001
[junit4] 2> 121985 WARN (SUITE-TestReplicationHandler-seed#[99A217A86175F907]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=44 numCloses=44
[junit4] 2> 121985 INFO (SUITE-TestReplicationHandler-seed#[99A217A86175F907]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 121986 INFO (SUITE-TestReplicationHandler-seed#[99A217A86175F907]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
[junit4] 2> 121988 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestStressReplication
[junit4] 2> 121988 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/collection1
[junit4] 2> 121990 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 121990 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 121990 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 121990 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+8
[junit4] 2> 121991 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 121991 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 121991 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 121991 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@76a2d615{/solr,null,AVAILABLE}
[junit4] 2> 121993 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a909a75{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:38921}
[junit4] 2> 121993 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server Started @122028ms
[junit4] 2> 121993 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/collection1/data, hostPort=38921}
[junit4] 2> 121994 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 121994 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 121994 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 121994 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 121994 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 121994 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-13T13:31:29.887491Z
[junit4] 2> 121994 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 121994 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/solr.xml
[junit4] 2> 121998 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 121998 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 121999 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 122068 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 122069 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2b50d2a6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 122075 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@59dd2c54[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 122090 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 122090 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 122104 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 122113 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 122113 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 122115 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/.
[junit4] 2> 122115 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 122126 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 122133 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 122134 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 122134 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 122134 INFO (coreLoadExecutor-141-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@6d1b059f
[junit4] 2> 122134 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/./collection1/data/]
[junit4] 2> 122136 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=50.83984375, floorSegmentMB=1.4150390625, forceMergeDeletesPctAllowed=29.47232908510891, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=28.96985811771573
[junit4] 2> 122230 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 122230 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 122230 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4567801047433391]
[junit4] 2> 122231 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e932f25[collection1] main]
[junit4] 2> 122232 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/collection1/conf
[junit4] 2> 122232 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 122232 INFO (coreLoadExecutor-141-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 122233 INFO (searcherExecutor-142-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6e932f25[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 122734 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1
[junit4] 2> 122735 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 122736 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 122736 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 122736 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+8
[junit4] 2> 122737 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 122737 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 122737 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 122737 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17c7ddd3{/solr,null,AVAILABLE}
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6016b027{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40053}
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server Started @122774ms
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data, hostPort=40053}
[junit4] 2> 122739 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-13T13:31:30.632616Z
[junit4] 2> 122739 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/solr.xml
[junit4] 2> 122741 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 122741 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 122743 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 123072 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 123073 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@256a5597[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 123077 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@db7039f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 123089 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 123089 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 123107 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123119 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123119 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123122 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/.
[junit4] 2> 123122 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 123128 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 123136 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 123136 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 123137 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 123137 INFO (coreLoadExecutor-154-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@6d1b059f
[junit4] 2> 123137 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/./collection1/data/]
[junit4] 2> 123138 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=50.83984375, floorSegmentMB=1.4150390625, forceMergeDeletesPctAllowed=29.47232908510891, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=28.96985811771573
[junit4] 2> 123173 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 123173 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 123174 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4567801047433391]
[junit4] 2> 123175 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6185739d[collection1] main]
[junit4] 2> 123176 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/conf
[junit4] 2> 123185 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 123185 INFO (coreLoadExecutor-154-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 123186 INFO (searcherExecutor-155-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6185739d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 123189 INFO (qtp828972489-209) [ 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> 123189 INFO (indexFetcher-159-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 123189 INFO (indexFetcher-159-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 123189 INFO (indexFetcher-159-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 123189 INFO (indexFetcher-159-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 123189 INFO (indexFetcher-159-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine...
[junit4] 2> 123190 INFO (indexFetcher-159-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e79e53f[collection1] main]
[junit4] 2> 123191 INFO (searcherExecutor-155-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6e79e53f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 123285 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1796037346
[junit4] 2> 123286 INFO (coreCloseExecutor-160-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2eac7093
[junit4] 2> 123286 INFO (coreCloseExecutor-160-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2eac7093
[junit4] 2> 123286 INFO (coreCloseExecutor-160-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13d1215e: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@10b97829
[junit4] 2> 123295 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 123306 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b86ab17: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@e1e3fc5
[junit4] 2> 123311 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 123311 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24b6519c: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@692e5de5
[junit4] 2> 123314 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 123314 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d825c42: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@9b81c63
[junit4] 2> 123319 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6016b027{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 123320 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@17c7ddd3{/solr,null,UNAVAILABLE}
[junit4] 2> 123320 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 123325 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 123326 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 123326 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 123326 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+8
[junit4] 2> 123338 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 123338 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 123338 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 123338 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2ae2e1b9{/solr,null,AVAILABLE}
[junit4] 2> 123340 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d7c64e6{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36517}
[junit4] 2> 123340 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server Started @123375ms
[junit4] 2> 123340 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data, hostPort=36517}
[junit4] 2> 123341 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 123341 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 123341 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 123341 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 123341 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 123341 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-13T13:31:31.234421Z
[junit4] 2> 123341 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/solr.xml
[junit4] 2> 123345 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 123345 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 123347 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 123423 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 123435 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7bcb1016[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 123443 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@17d36fb4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 123462 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 123462 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 123479 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123489 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123489 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123491 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/.
[junit4] 2> 123491 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 123545 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 123565 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 123566 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 123566 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 123567 INFO (coreLoadExecutor-170-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@6d1b059f
[junit4] 2> 123569 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/./collection1/data/]
[junit4] 2> 123571 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=74.8017578125, floorSegmentMB=2.18359375, forceMergeDeletesPctAllowed=19.130735731989063, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48112063872302113, deletesPctAllowed=46.889992209966984
[junit4] 2> 123623 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 123623 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 123624 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=16, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 123625 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@60db32f9[collection1] main]
[junit4] 2> 123625 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/conf
[junit4] 2> 123625 INFO (coreLoadExecutor-170-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 123626 INFO (searcherExecutor-171-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@60db32f9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 123627 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1517624668
[junit4] 2> 123628 INFO (coreCloseExecutor-175-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2a04a612
[junit4] 2> 123628 INFO (coreCloseExecutor-175-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2a04a612
[junit4] 2> 123628 INFO (coreCloseExecutor-175-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55231938: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@70017017
[junit4] 2> 123634 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 123634 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6077805a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@525e13db
[junit4] 2> 123637 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 123637 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@72dc294: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@692e5de5
[junit4] 2> 123639 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 123639 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@25824de9: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@9b81c63
[junit4] 2> 123647 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6a909a75{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 123647 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@76a2d615{/solr,null,UNAVAILABLE}
[junit4] 2> 123647 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 123648 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 123648 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 123648 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 123648 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+8
[junit4] 2> 123657 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 123657 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 123657 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 123657 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@67732727{/solr,null,AVAILABLE}
[junit4] 2> 123658 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4a492be7{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39153}
[junit4] 2> 123658 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.s.Server Started @123693ms
[junit4] 2> 123658 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/collection1/data, hostPort=39153}
[junit4] 2> 123658 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 123658 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 123658 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 123658 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 123658 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 123659 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-13T13:31:31.552006Z
[junit4] 2> 123659 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/solr.xml
[junit4] 2> 123662 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 123662 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 123663 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 123826 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 123829 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@33eb5122[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 123832 WARN (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@41adc0c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 123858 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 123859 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 123873 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123885 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123886 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d1b059f
[junit4] 2> 123888 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/.
[junit4] 2> 123888 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 123896 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 123909 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 123911 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 123911 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 123911 INFO (coreLoadExecutor-185-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@6d1b059f
[junit4] 2> 123911 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/./collection1/data/]
[junit4] 2> 123914 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=74.8017578125, floorSegmentMB=2.18359375, forceMergeDeletesPctAllowed=19.130735731989063, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48112063872302113, deletesPctAllowed=46.889992209966984
[junit4] 2> 123977 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 123977 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 123978 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=16, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 123979 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a24bf49[collection1] main]
[junit4] 2> 123979 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-001/collection1/conf
[junit4] 2> 123979 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 123979 INFO (coreLoadExecutor-185-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 123981 INFO (searcherExecutor-186-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a24bf49[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 123986 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 123987 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 1
[junit4] 2> 123995 INFO (qtp1162326478-257) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 123996 INFO (qtp1162326478-257) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 1
[junit4] 2> 124000 INFO (qtp1162326478-258) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 124000 INFO (qtp1162326478-258) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@29bdb416 commitCommandVersion:0
[junit4] 2> 124016 INFO (qtp1162326478-258) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2345df66[collection1] main]
[junit4] 2> 124016 INFO (qtp1162326478-258) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 124017 INFO (searcherExecutor-171-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2345df66[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 124017 INFO (qtp1162326478-258) [ 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 17
[junit4] 2> 124019 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1
[junit4] 2> 124027 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 124028 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 124031 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 124033 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 124034 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 124036 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 124037 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 124039 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 124040 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 124041 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 124042 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 124044 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 124045 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 124046 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 124048 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 124049 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 124051 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 124052 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 124053 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 124055 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 124056 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 124058 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 124059 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 124061 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 124063 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 124068 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 124073 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 124078 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 124086 INFO (qtp1507571123-299) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 124088 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 124090 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 124091 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 124093 INFO (qtp1507571123-299) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 124095 INFO (qtp1507571123-299) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 124098 INFO (qtp1507571123-299) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 124100 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 124101 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 124106 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 124107 INFO (qtp1507571123-299) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 124114 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 124114 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2bd88e8f commitCommandVersion:0
[junit4] 2> 124194 INFO (qtp1507571123-281) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f175830[collection1] main]
[junit4] 2> 124195 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 124196 INFO (searcherExecutor-186-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f175830[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C40:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=4.18.0-15-generic, timestamp=1552483892011}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 124196 INFO (qtp1507571123-281) [ 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 81
[junit4] 2> 124202 INFO (qtp1507571123-282) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=40 status=0 QTime=4
[junit4] 2> 124204 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 40 docs
[junit4] 2> 124208 INFO (qtp1162326478-257) [ x:collection1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=collection1&action=status&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 124211 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 124213 INFO (qtp1507571123-299) [ 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> 124214 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 124214 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1552483892007
[junit4] 2> 124214 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 124214 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1552483891893
[junit4] 2> 124214 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 124218 INFO (qtp1507571123-281) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=3
[junit4] 2> 124228 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 124231 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MMapDirectory@/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data/index.20190313073132124 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5b3c7d16
[junit4] 2> 124231 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher tmpIndexDir_type : class org.apache.lucene.store.MMapDirectory , MMapDirectory@/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data/index.20190313073132124 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5b3c7d16
[junit4] 2> 124240 INFO (qtp1507571123-282) [ 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=2
[junit4] 2> 124247 INFO (qtp1507571123-283) [ 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> 124248 INFO (qtp1507571123-299) [ 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> 124251 INFO (qtp1507571123-281) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124252 INFO (qtp1507571123-282) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124255 INFO (qtp1507571123-283) [ 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> 124257 INFO (qtp1507571123-299) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124259 INFO (qtp1507571123-281) [ 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> 124260 INFO (qtp1507571123-282) [ 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> 124261 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher File segments_2 did not match. expected checksum is 2243527269 and actual is checksum 3689703122. expected length is 186 and actual length is 117
[junit4] 2> 124262 INFO (qtp1507571123-283) [ 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> 124263 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 2503, Bytes skipped downloading: 0
[junit4] 2> 124263 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=2503) : 0 secs (null bytes/sec) to MMapDirectory@/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data/index.20190313073132124 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5b3c7d16
[junit4] 2> 124300 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrCore Updating index properties... index=index.20190313073132124
[junit4] 2> 124312 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher removing old index directory MMapDirectory@/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f6ab88b
[junit4] 2> 124313 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=74.8017578125, floorSegmentMB=2.18359375, forceMergeDeletesPctAllowed=19.130735731989063, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48112063872302113, deletesPctAllowed=46.889992209966984
[junit4] 2> 124315 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 124317 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@41941cb0[collection1] main]
[junit4] 2> 124318 INFO (searcherExecutor-171-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41941cb0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C40:[diagnostics={java.runtime.version=13-ea+8, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os=Linux, os.arch=amd64, os.version=4.18.0-15-generic, source=flush, timestamp=1552483892011}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 124318 INFO (qtp1162326478-258) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:39153/solr/collection1/replication&command=fetchindex} status=0 QTime=107
[junit4] 2> 124326 INFO (qtp1162326478-256) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=40 status=0 QTime=1
[junit4] 2> 124326 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 40 docs
[junit4] 2> 124331 INFO (qtp1507571123-299) [ 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> 124333 INFO (qtp1507571123-281) [ 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> 124337 INFO (qtp1507571123-282) [ 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> 124338 INFO (qtp1162326478-257) [ 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=3
[junit4] 2> 124339 INFO (qtp1507571123-283) [ 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> 124342 INFO (qtp1162326478-258) [ 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] 1> [index, snapshot_metadata]
[junit4] 1> [snapshot_metadata, replication.properties, index.properties, index.20190313073132124]
[junit4] 2> 124346 INFO (qtp1507571123-299) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 124347 INFO (qtp1507571123-281) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 124348 INFO (qtp1507571123-282) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 124350 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 124353 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2bd88e8f commitCommandVersion:0
[junit4] 2> 124433 INFO (qtp1507571123-283) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@30bb1b15[collection1] main]
[junit4] 2> 124434 INFO (qtp1507571123-283) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 124435 INFO (searcherExecutor-186-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@30bb1b15[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C40:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=4.18.0-15-generic, timestamp=1552483892011}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.1.0):C3:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=13-ea, java.vm.version=13-ea+8, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=13-ea+8, source=flush, os.version=4.18.0-15-generic, timestamp=1552483892248}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 124435 INFO (qtp1507571123-283) [ 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 85
[junit4] 2> 124437 INFO (qtp1507571123-299) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=43 status=0 QTime=0
[junit4] 2> 124438 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[99A217A86175F907]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 43 docs
[junit4] 2> 124441 INFO (qtp1162326478-257) [ x:collection1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=collection1&action=status&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 124443 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 124444 INFO (qtp1507571123-281) [ 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> 124444 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 124445 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1552483892246
[junit4] 2> 124445 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 124445 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1552483892007
[junit4] 2> 124445 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 124448 INFO (qtp1507571123-282) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=3
[junit4] 2> 124449 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 19
[junit4] 2> 124452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=74.8017578125, floorSegmentMB=2.18359375, forceMergeDeletesPctAllowed=19.130735731989063, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48112063872302113, deletesPctAllowed=46.889992209966984
[junit4] 2> 124453 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 124453 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MMapDirectory@/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data/index.20190313073132342 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15b4233a
[junit4] 2> 124453 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher tmpIndexDir_type : class org.apache.lucene.store.MMapDirectory , MMapDirectory@/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data/index.20190313073132342 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15b4233a
[junit4] 2> 124455 INFO (qtp1507571123-283) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124456 INFO (qtp1507571123-299) [ 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> 124458 INFO (qtp1507571123-281) [ 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> 124459 INFO (qtp1507571123-282) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124461 INFO (qtp1507571123-283) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124462 INFO (qtp1507571123-299) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124463 INFO (qtp1507571123-281) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124465 INFO (qtp1507571123-282) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124466 INFO (qtp1507571123-283) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124468 INFO (qtp1507571123-299) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124469 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 1824, Bytes skipped downloading: 0
[junit4] 2> 124469 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1824) : 0 secs (null bytes/sec) to MMapDirectory@/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1/data/index.20190313073132342 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15b4233a
[junit4] 2> 124469 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download of configuration files from master: [{size=1530, name=schema.xml, checksum=1508380349}]
[junit4] 2> 124471 INFO (qtp1507571123-281) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&cf=schema.xml&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 124513 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Configuration files are modified, core will be reloaded
[junit4] 2> 124523 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=16, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 124524 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 124524 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Reloading SolrCore collection1
[junit4] 2> 124540 INFO (Thread-68) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 124546 INFO (Thread-68) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 124547 INFO (Thread-68) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 124547 INFO (Thread-68) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/./collection1
[junit4] 2> 124570 INFO (Thread-68) [ 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@6d1b059f
[junit4] 2> 124570 INFO (Thread-68) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/./collection1/data/]
[junit4] 2> 124573 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.DirectoryFactory Will not remove most recent old directory after reload /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-002/./collection1/data/index.20190313073132342
[junit4] 2> 124573 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.DirectoryFactory Found 0 old index directories to clean-up under /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/
[...truncated too long message...]
4775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4567801047433391]
[junit4] 2> 200202 INFO (coreLoadExecutor-1109-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c2f1d5d[collection1] main]
[junit4] 2> 200202 INFO (coreLoadExecutor-1109-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001/solr-instance-042/collection1/conf
[junit4] 2> 200203 INFO (coreLoadExecutor-1109-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 200203 INFO (coreLoadExecutor-1109-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 200203 INFO (searcherExecutor-1110-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6c2f1d5d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 200204 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestHandlerPathUnchanged
[junit4] 2> 200204 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1000023311
[junit4] 2> 200204 INFO (coreCloseExecutor-1115-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1e41d916
[junit4] 2> 200204 INFO (coreCloseExecutor-1115-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1e41d916
[junit4] 2> 200204 INFO (coreCloseExecutor-1115-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7ef07031: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@642ac5fb
[junit4] 2> 200209 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 200209 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60427583: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@30e54b1e
[junit4] 2> 200211 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 200211 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@615ba893: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@692e5de5
[junit4] 2> 200213 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 200213 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@59c552af: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@9b81c63
[junit4] 2> 200215 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@62edd659{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 200215 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4702e633{/solr,null,UNAVAILABLE}
[junit4] 2> 200215 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 200216 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=656697763
[junit4] 2> 200216 INFO (coreCloseExecutor-1117-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6d24f51
[junit4] 2> 200216 INFO (coreCloseExecutor-1117-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=6d24f51
[junit4] 2> 200216 INFO (coreCloseExecutor-1117-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@62de611c: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2cede6bc
[junit4] 2> 200223 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 200223 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2b6bfa5a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2f3b1a1a
[junit4] 2> 200227 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 200227 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e36d4da: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@692e5de5
[junit4] 2> 200229 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 200229 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2a915802: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@9b81c63
[junit4] 2> 200230 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@583f2350{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 200230 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c44621{/solr,null,UNAVAILABLE}
[junit4] 2> 200230 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[99A217A86175F907]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_99A217A86175F907-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3e9c2b8e), locale=eo, timezone=America/Ojinaga
[junit4] 2> NOTE: Linux 4.18.0-15-generic amd64/Oracle Corporation 13-ea (64-bit)/cpus=8,threads=1,free=180630472,total=477102080
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrFieldCacheBean, TestSSLRandomization, TokenizerChainTest, TestAddFieldRealTimeGet, DistributedDebugComponentTest, TestInPlaceUpdatesStandalone, RAMDirectoryFactoryTest, TestGraphMLResponseWriter, TestRemoteStreaming, BinaryUpdateRequestHandlerTest, SpellCheckCollatorWithCollapseTest, TestReplicationHandler]
[junit4] Completed [57/845 (1!)] on J1 in 78.39s, 20 tests, 1 failure <<< FAILURES!
[...truncated 45330 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/258/consoleText
[repro] Revision: 39adb09fc9e9f746adba0edeb8e5cec807d29cce
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestReplicationHandler -Dtests.method=doTestIndexAndConfigReplication -Dtests.seed=99A217A86175F907 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=eo -Dtests.timezone=America/Ojinaga -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestReplicationHandler
[repro] ant compile-test
[...truncated 3580 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestReplicationHandler" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=99A217A86175F907 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=eo -Dtests.timezone=America/Ojinaga -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 170 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.handler.TestReplicationHandler
[repro] Exiting with code 0
[...truncated 43 lines...]