You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/12/31 19:39:51 UTC
[JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 371 -
Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/371/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication
Error Message:
Index: 0, Size: 0
Stack Trace:
java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
at __randomizedtesting.SeedInfo.seed([30FAE5EFDBC7B0AE:24B2BEBAF8C00DB0]:0)
at java.util.ArrayList.rangeCheck(ArrayList.java:653)
at java.util.ArrayList.get(ArrayList.java:429)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12204 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 787974 INFO (SUITE-TestReplicationHandler-seed#[30FAE5EFDBC7B0AE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/init-core-data-001
[junit4] 2> 787975 WARN (SUITE-TestReplicationHandler-seed#[30FAE5EFDBC7B0AE]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 787975 INFO (SUITE-TestReplicationHandler-seed#[30FAE5EFDBC7B0AE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 787978 INFO (SUITE-TestReplicationHandler-seed#[30FAE5EFDBC7B0AE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 787979 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 787979 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1
[junit4] 2> 787988 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 787989 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6cf84efe{/solr,null,AVAILABLE}
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@29ba962d{HTTP/1.1,[http/1.1]}{127.0.0.1:56616}
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server Started @791244ms
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=56616}
[junit4] 2> 787991 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T18:36:38.938Z
[junit4] 2> 787991 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/solr.xml
[junit4] 2> 787996 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 787996 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 787997 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 788038 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788044 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788044 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788046 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/.
[junit4] 2> 788047 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 788047 INFO (coreLoadExecutor-2753-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 788065 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 788070 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 788072 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 788072 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 788073 INFO (coreLoadExecutor-2753-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@273946be
[junit4] 2> 788073 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 788073 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/./collection1/data/]
[junit4] 2> 788074 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=95.1201171875, floorSegmentMB=1.4306640625, forceMergeDeletesPctAllowed=21.370371859346882, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27646467960975185
[junit4] 2> 788115 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 788115 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 788115 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4861376921716811]
[junit4] 2> 788115 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@23a47280[collection1] main]
[junit4] 2> 788116 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1/conf
[junit4] 2> 788116 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 788116 INFO (coreLoadExecutor-2753-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 788117 INFO (searcherExecutor-2754-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@23a47280[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 788119 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/collection1
[junit4] 2> 788123 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 788125 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7eaf9148{/solr,null,AVAILABLE}
[junit4] 2> 788125 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@13531e2{HTTP/1.1,[http/1.1]}{127.0.0.1:56617}
[junit4] 2> 788125 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server Started @791378ms
[junit4] 2> 788125 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=56617}
[junit4] 2> 788125 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 788126 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 788126 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 788126 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 788126 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T18:36:39.073Z
[junit4] 2> 788126 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/solr.xml
[junit4] 2> 788130 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 788130 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 788131 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 788175 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788183 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788183 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788185 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/.
[junit4] 2> 788185 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 788186 INFO (coreLoadExecutor-2763-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 788202 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 788206 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 788208 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 788209 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 788209 INFO (coreLoadExecutor-2763-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@273946be
[junit4] 2> 788209 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 788209 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/./collection1/data/]
[junit4] 2> 788210 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=95.1201171875, floorSegmentMB=1.4306640625, forceMergeDeletesPctAllowed=21.370371859346882, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27646467960975185
[junit4] 2> 788262 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 788262 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 788263 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4861376921716811]
[junit4] 2> 788263 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@414ef86c[collection1] main]
[junit4] 2> 788264 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/collection1/conf
[junit4] 2> 788266 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 788266 INFO (coreLoadExecutor-2763-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 788267 INFO (searcherExecutor-2764-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@414ef86c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 788268 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@13531e2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 788268 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=169138167
[junit4] 2> 788268 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 788268 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4561ce07: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6f38abea
[junit4] 2> 788273 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 788273 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c695045: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41467a66
[junit4] 2> 788276 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 788276 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7f1b4ca9: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2b6ffeeb
[junit4] 2> 788277 INFO (coreCloseExecutor-2769-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50bff2d4
[junit4] 2> 788277 INFO (coreCloseExecutor-2769-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1354756820
[junit4] 2> 788277 INFO (coreCloseExecutor-2769-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67e6bb19: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6fa9154
[junit4] 2> 788284 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7eaf9148{/solr,null,UNAVAILABLE}
[junit4] 2> 788286 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@29ba962d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 788286 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=167098711
[junit4] 2> 788286 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 788286 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@682cbd3a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5d4a645a
[junit4] 2> 788287 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 788287 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24f08038: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41467a66
[junit4] 2> 788288 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 788288 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5ae466af: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2b6ffeeb
[junit4] 2> 788288 INFO (coreCloseExecutor-2771-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2fd26d8f
[junit4] 2> 788288 INFO (coreCloseExecutor-2771-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=802319759
[junit4] 2> 788289 INFO (coreCloseExecutor-2771-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b70e339: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3bd8ebf
[junit4] 2> 788291 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6cf84efe{/solr,null,UNAVAILABLE}
[junit4] 2> 788292 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 788293 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3eb231c2{/solr,null,AVAILABLE}
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@98a55ac{HTTP/1.1,[http/1.1]}{127.0.0.1:56618}
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server Started @791547ms
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=56618}
[junit4] 2> 788294 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T18:36:39.241Z
[junit4] 2> 788294 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/solr.xml
[junit4] 2> 788299 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 788299 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 788299 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 788337 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788344 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788344 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788345 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/.
[junit4] 2> 788345 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 788346 INFO (coreLoadExecutor-2778-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 788362 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 788370 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 788372 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 788372 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 788373 INFO (coreLoadExecutor-2778-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@273946be
[junit4] 2> 788373 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 788373 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/./collection1/data/]
[junit4] 2> 788374 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=95.1201171875, floorSegmentMB=1.4306640625, forceMergeDeletesPctAllowed=21.370371859346882, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27646467960975185
[junit4] 2> 788415 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 788415 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 788415 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4861376921716811]
[junit4] 2> 788415 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b87d11f[collection1] main]
[junit4] 2> 788416 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1/conf
[junit4] 2> 788416 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 788416 INFO (coreLoadExecutor-2778-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 788417 INFO (searcherExecutor-2779-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b87d11f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 788421 INFO (qtp733165745-7831) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 788421 INFO (qtp733165745-7831) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 788422 INFO (qtp733165745-7832) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 788422 INFO (qtp733165745-7832) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@148509d1 commitCommandVersion:0
[junit4] 2> 788422 INFO (qtp733165745-7832) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b7fff0a[collection1] main]
[junit4] 2> 788423 INFO (qtp733165745-7832) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 788424 INFO (searcherExecutor-2779-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b7fff0a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 788424 INFO (qtp733165745-7832) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 788424 INFO (qtp733165745-7833) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 788425 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 788426 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e63e50b{/solr,null,AVAILABLE}
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@70ee33dc{HTTP/1.1,[http/1.1]}{127.0.0.1:56620}
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server Started @791681ms
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=56620}
[junit4] 2> 788428 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 788428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T18:36:39.375Z
[junit4] 2> 788429 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/solr.xml
[junit4] 2> 788434 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 788434 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 788434 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 788476 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788488 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788488 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 788490 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/.
[junit4] 2> 788490 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 788490 INFO (coreLoadExecutor-2788-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 788506 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 788511 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 788513 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 788513 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 788513 INFO (coreLoadExecutor-2788-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@273946be
[junit4] 2> 788514 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 788514 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/./collection1/data/]
[junit4] 2> 788515 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=95.1201171875, floorSegmentMB=1.4306640625, forceMergeDeletesPctAllowed=21.370371859346882, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27646467960975185
[junit4] 2> 788560 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 788560 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 788560 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4861376921716811]
[junit4] 2> 788560 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65b5f92[collection1] main]
[junit4] 2> 788561 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-002/collection1/conf
[junit4] 2> 788563 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 788563 INFO (coreLoadExecutor-2788-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 788568 INFO (searcherExecutor-2789-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65b5f92[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 788570 INFO (qtp1359208140-7849) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 788571 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 788571 INFO (qtp733165745-7834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 788572 INFO (qtp733165745-7835) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 788587 INFO (qtp733165745-7829) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 788591 INFO (qtp733165745-7829) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 788593 INFO (qtp733165745-7829) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 788593 INFO (qtp733165745-7829) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/./collection1
[junit4] 2> 788610 INFO (qtp733165745-7829) [ 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@273946be
[junit4] 2> 788611 INFO (qtp733165745-7829) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/./collection1/data/]
[junit4] 2> 788658 INFO (qtp733165745-7829) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 788658 INFO (qtp733165745-7829) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 788660 INFO (qtp733165745-7829) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b6cfd9[collection1] main]
[junit4] 2> 788660 INFO (qtp733165745-7829) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-001/collection1/conf
[junit4] 2> 788660 INFO (qtp733165745-7829) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 788660 INFO (qtp733165745-7829) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 788661 INFO (qtp733165745-7829) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=405362074, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 788661 INFO (qtp733165745-7829) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 788662 INFO (searcherExecutor-2794-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b6cfd9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 788662 INFO (qtp733165745-7829) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2882c5dc[collection1] main]
[junit4] 2> 788662 INFO (qtp733165745-7829) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3b3872ff
[junit4] 2> 788662 INFO (qtp733165745-7829) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=993555199
[junit4] 2> 788662 INFO (qtp733165745-7829) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5bdb8e4c: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7ad9f339
[junit4] 2> 788663 INFO (searcherExecutor-2794-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2882c5dc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 788673 INFO (qtp733165745-7829) [ x:collection1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=collection1&qt=/admin/cores&action=reload&wt=javabin&version=2} status=0 QTime=99
[junit4] 2> 788674 INFO (qtp733165745-7831) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 788675 INFO (qtp733165745-7833) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 788675 INFO (qtp733165745-7834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 788676 INFO (qtp733165745-7835) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 788676 INFO (qtp733165745-7836) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 788676 INFO (qtp733165745-7836) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1bc6e91f commitCommandVersion:0
[junit4] 2> 788679 INFO (qtp733165745-7836) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17d5e4cb[collection1] main]
[junit4] 2> 788679 INFO (qtp733165745-7836) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 788680 INFO (searcherExecutor-2794-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17d5e4cb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c2)))}
[junit4] 2> 788680 INFO (qtp733165745-7836) [ 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 4
[junit4] 2> 788681 INFO (qtp733165745-7829) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 788681 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 2 docs
[junit4] 2> 788682 INFO (qtp1359208140-7850) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 788682 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 788769 INFO (qtp733165745-7832) [ 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> 788770 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 788770 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1514745399623
[junit4] 2> 788770 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 788770 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 788770 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 788776 INFO (qtp733165745-7833) [ 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=5
[junit4] 2> 788776 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 788777 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8191794639014874]
[junit4] 2> 788778 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 788778 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@67724009 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f31a736)
[junit4] 2> 788779 INFO (qtp733165745-7834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.cfe&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 788782 INFO (qtp733165745-7835) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.si&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 788785 INFO (qtp733165745-7836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_0.cfs&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 788787 INFO (qtp1359208140-7851) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 788787 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.h.TestReplicationHandler Waiting for 2 docs
[junit4] 2> 788787 INFO (qtp733165745-7829) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 788788 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2004) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@67724009 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f31a736)
[junit4] 2> 788790 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4203545650509839]
[junit4] 2> 788792 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 788792 INFO (indexFetcher-2793-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a4747d9[collection1] main]
[junit4] 2> 788795 INFO (searcherExecutor-2789-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a4747d9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c2)))}
[junit4] 2> 788890 INFO (qtp1359208140-7852) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 788890 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.h.TestReplicationHandler Waited for 200ms and found 2 docs
[junit4] 2> 788890 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestReplicateAfterCoreReload
[junit4] 2> 788891 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@98a55ac{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 788891 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1649990864
[junit4] 2> 788891 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 788892 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4841ae15: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@75e1208f
[junit4] 2> 788896 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 788896 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3a6a6b2: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41467a66
[junit4] 2> 788900 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 788900 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2d6dc887: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2b6ffeeb
[junit4] 2> 788901 INFO (coreCloseExecutor-2799-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1006f6ff
[junit4] 2> 788901 INFO (coreCloseExecutor-2799-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=268891903
[junit4] 2> 788901 INFO (coreCloseExecutor-2799-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c81fff9: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7ad9f339
[junit4] 2> 788912 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3eb231c2{/solr,null,UNAVAILABLE}
[junit4] 2> 788914 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@70ee33dc{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 788914 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1969970130
[junit4] 2> 788915 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 788915 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@551e02a8: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@554a12c7
[junit4] 2> 788921 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 788921 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@50abbce5: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41467a66
[junit4] 2> 788927 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 788927 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@411c87ed: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2b6ffeeb
[junit4] 2> 788928 INFO (coreCloseExecutor-2801-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2899ba9d
[junit4] 2> 788928 INFO (coreCloseExecutor-2801-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=681163421
[junit4] 2> 788928 INFO (coreCloseExecutor-2801-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55964f26: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5926a044
[junit4] 2> 788933 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7e63e50b{/solr,null,UNAVAILABLE}
[junit4] 2> 788937 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestStopPoll
[junit4] 2> 788938 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/collection1
[junit4] 2> 788942 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 788944 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7871284b{/solr,null,AVAILABLE}
[junit4] 2> 788944 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@55ebd5e2{HTTP/1.1,[http/1.1]}{127.0.0.1:56624}
[junit4] 2> 788945 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server Started @792197ms
[junit4] 2> 788945 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=56624}
[junit4] 2> 788945 ERROR (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 788945 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 788945 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 788945 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 788945 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T18:36:39.892Z
[junit4] 2> 788946 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/solr.xml
[junit4] 2> 788953 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 788953 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 788954 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 789010 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 789017 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 789017 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 789020 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/.
[junit4] 2> 789020 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 789020 INFO (coreLoadExecutor-2808-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 789042 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 789045 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 789051 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 789051 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/./collection1, trusted=true
[junit4] 2> 789052 INFO (coreLoadExecutor-2808-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@273946be
[junit4] 2> 789052 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 789052 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/./collection1/data/]
[junit4] 2> 789053 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=95.1201171875, floorSegmentMB=1.4306640625, forceMergeDeletesPctAllowed=21.370371859346882, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27646467960975185
[junit4] 2> 789110 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 789110 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 789111 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4861376921716811]
[junit4] 2> 789111 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@20df3c7d[collection1] main]
[junit4] 2> 789111 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-003/collection1/conf
[junit4] 2> 789112 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 789112 INFO (coreLoadExecutor-2808-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 789113 INFO (searcherExecutor-2809-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@20df3c7d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 789114 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/collection1
[junit4] 2> 789119 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 789120 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@43722c1a{/solr,null,AVAILABLE}
[junit4] 2> 789121 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@239124a4{HTTP/1.1,[http/1.1]}{127.0.0.1:56625}
[junit4] 2> 789121 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.Server Started @792374ms
[junit4] 2> 789121 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=56625}
[junit4] 2> 789121 ERROR (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 789121 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 789121 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 789121 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 789121 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T18:36:40.068Z
[junit4] 2> 789122 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/solr.xml
[junit4] 2> 789126 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 789126 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 789126 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 789176 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 789184 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 789184 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@273946be
[junit4] 2> 789186 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/.
[junit4] 2> 789186 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 789186 INFO (coreLoadExecutor-2818-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 789201 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 789206 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 789208 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 789208 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/./collection1, trusted=true
[junit4] 2> 789208 INFO (coreLoadExecutor-2818-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@273946be
[junit4] 2> 789208 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 789208 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/./collection1/data/]
[junit4] 2> 789210 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=95.1201171875, floorSegmentMB=1.4306640625, forceMergeDeletesPctAllowed=21.370371859346882, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27646467960975185
[junit4] 2> 789257 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 789257 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 789258 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4861376921716811]
[junit4] 2> 789258 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6455e020[collection1] main]
[junit4] 2> 789259 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001/solr-instance-004/collection1/conf
[junit4] 2> 789261 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 789261 INFO (coreLoadExecutor-2818-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 789261 INFO (searcherExecutor-2819-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6455e020[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 789263 INFO (qtp1457723376-7878) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 789264 INFO (qtp1457723376-7879) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 789265 INFO (qtp1457723376-7880) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 789265 INFO (qtp1457723376-7881) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 789266 INFO (qtp1457723376-7882) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 789266 INFO (qtp1457723376-7883) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 789267 INFO (qtp1457723376-7876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 789267 INFO (qtp1457723376-7878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 789268 INFO (qtp1457723376-7878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 789269 INFO (qtp1457723376-7878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 789270 INFO (qtp1457723376-7878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 789270 INFO (qtp1457723376-7882) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 789271 INFO (qtp1457723376-7883) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 789271 INFO (qtp1457723376-7876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 789271 INFO (qtp1457723376-7879) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 789272 INFO (qtp1457723376-7880) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 789272 INFO (qtp1457723376-7881) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 789273 INFO (qtp1457723376-7878) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 789273 INFO (qtp1457723376-7882) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 789274 INFO (qtp1457723376-7883) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 789274 INFO (qtp1457723376-7876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 7892
[...truncated too long message...]
0AE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@65e003fb{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 872036 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1890569202
[junit4] 2> 872036 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 872036 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c93f8e0: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@70cd3ca9
[junit4] 2> 872037 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 872037 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e7d8d95: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41467a66
[junit4] 2> 872044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 872044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55277654: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2b6ffeeb
[junit4] 2> 872045 INFO (coreCloseExecutor-3426-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1dcf254a
[junit4] 2> 872045 INFO (coreCloseExecutor-3426-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=500114762
[junit4] 2> 872045 INFO (coreCloseExecutor-3426-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61dcba47: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@c79a071
[junit4] 2> 872067 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@541ed444{/solr,null,UNAVAILABLE}
[junit4] 2> 872069 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3e3b75f9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 872069 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1812235256
[junit4] 2> 872069 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 872069 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4300308b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@19031c15
[junit4] 2> 872077 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 872077 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@677e5b18: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@41467a66
[junit4] 2> 872085 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 872085 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2ff7daa7: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2b6ffeeb
[junit4] 2> 872086 INFO (coreCloseExecutor-3428-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@11eeb6e9
[junit4] 2> 872086 INFO (coreCloseExecutor-3428-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=300857065
[junit4] 2> 872086 INFO (coreCloseExecutor-3428-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c6666ad: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@169f24aa
[junit4] 2> 872100 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[30FAE5EFDBC7B0AE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@661a7700{/solr,null,UNAVAILABLE}
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_30FAE5EFDBC7B0AE-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=LuceneFixedGap), newname=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, maxPointsInLeafNode=1690, maxMBSortInHeap=7.696922989630577, sim=RandomSimilarity(queryNorm=false): {}, locale=es-US, timezone=Iceland
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=176808040,total=237273088
[junit4] 2> NOTE: All tests run in this JVM: [SolrXmlInZkTest, TestSegmentSorting, TestScoreJoinQPNoScore, OverseerModifyCollectionTest, TestSolr4Spatial, IndexBasedSpellCheckerTest, RulesTest, CdcrBidirectionalTest, RankQueryTest, DistributedQueryElevationComponentTest, TestPayloadCheckQParserPlugin, HdfsThreadLeakTest, TestJmxIntegration, TestManagedSynonymGraphFilterFactory, CollectionTooManyReplicasTest, TestFieldCacheReopen, SolrIndexMetricsTest, TestSchemaSimilarityResource, HdfsBasicDistributedZk2Test, TestDistribStateManager, TestComplexPhraseLeadingWildcard, TestManagedStopFilterFactory, SortByFunctionTest, TestTolerantUpdateProcessorRandomCloud, ConfigSetsAPITest, RecoveryZkTest, MigrateRouteKeyTest, DefaultValueUpdateProcessorTest, BinaryUpdateRequestHandlerTest, ChaosMonkeyNothingIsSafeTest, TestSmileRequest, CloneFieldUpdateProcessorFactoryTest, TestSolrXml, FullHLLTest, DistributedSpellCheckComponentTest, TestHdfsBackupRestoreCore, SubstringBytesRefFilterTest, TestSubQueryTransformerDistrib, TlogReplayBufferedWhileIndexingTest, ZkStateReaderTest, ConfigureRecoveryStrategyTest, SpatialHeatmapFacetsTest, TestCloudSearcherWarming, SliceStateTest, DeleteShardTest, SpellCheckComponentTest, TestSolrQueryResponse, TestInPlaceUpdatesDistrib, AsyncCallRequestStatusResponseTest, TestPHPSerializedResponseWriter, TestCloudNestedDocsSort, SolrGangliaReporterTest, TestHalfAndHalfDocValues, TestRuleBasedAuthorizationPlugin, TestReqParamsAPI, SolrMetricReporterTest, UninvertDocValuesMergePolicyTest, TestMacroExpander, SuggesterTSTTest, TemplateUpdateProcessorTest, TestDeleteCollectionOnDownNodes, TestConfigSetsAPI, DateFieldTest, DistributedQueryComponentOptimizationTest, TestQueryWrapperFilter, TestPolicyCloud, TestSearcherReuse, OverseerStatusTest, TestAtomicUpdateErrorCases, AddReplicaTest, DistribJoinFromCollectionTest, TestWriterPerf, TestSubQueryTransformer, TestLFUCache, TestReplicationHandler]
[junit4] Completed [178/764 (1!)] on J0 in 84.14s, 16 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 1998 lines...]
[junit4] JVM J0: stdout was not empty, see: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20171231_182327_6262857466627263158767.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/heapdumps/java_pid33877.hprof ...
[junit4] Heap dump file created [146264375 bytes in 1.961 secs]
[junit4] <<< JVM J0: EOF ----
[...truncated 8564 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/build.xml:835: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/build.xml:787: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created:
* java_pid33877.hprof
Total time: 95 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any