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 2018/07/27 13:10:38 UTC
[JENKINS] Lucene-Solr-7.x-Windows (64bit/jdk-10.0.1) - Build # 718
- Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/718/
Java: 64bit/jdk-10.0.1 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
found:2[index.20180727153422284, index.20180727153435325, index.properties, replication.properties, snapshot_metadata]
Stack Trace:
java.lang.AssertionError: found:2[index.20180727153422284, index.20180727153435325, index.properties, replication.properties, snapshot_metadata]
at __randomizedtesting.SeedInfo.seed([41F1E1EB9DAD9C21:9A5AE12D9885F592]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:969)
at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:940)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:916)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 13647 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\init-core-data-001
[junit4] 2> 1265998 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10
[junit4] 2> 1265999 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1265999 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1265999 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1265999 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5cc715cf{/solr,null,AVAILABLE}
[junit4] 2> 1266001 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7810aae9{HTTP/1.1,[http/1.1]}{127.0.0.1:52560}
[junit4] 2> 1266001 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.Server Started @1266065ms
[junit4] 2> 1266001 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-001\collection1\data, hostPort=52560}
[junit4] 2> 1266002 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1266002 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1266002 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 1266002 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1266002 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1266002 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-27T12:33:58.936451800Z
[junit4] 2> 1266002 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-001\solr.xml
[junit4] 2> 1266006 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1266006 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1266007 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1266248 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1266248 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1266271 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1266281 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1266282 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1266287 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-001\.
[junit4] 2> 1266287 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1266297 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 1266303 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1266304 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1266304 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-001\.\collection1, trusted=true
[junit4] 2> 1266304 INFO (coreLoadExecutor-6967-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@62a6fba6
[junit4] 2> 1266304 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1266305 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 1266306 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6378175394632903]
[junit4] 2> 1266376 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1266376 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1266376 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1266376 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e7093e6[collection1] main]
[junit4] 2> 1266377 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-001\collection1\conf
[junit4] 2> 1266378 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1266378 INFO (coreLoadExecutor-6967-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1266379 INFO (searcherExecutor-6968-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e7093e6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1266384 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10
[junit4] 2> 1266384 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1266385 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1266385 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1266385 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45632058{/solr,null,AVAILABLE}
[junit4] 2> 1266386 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@71284ddd{HTTP/1.1,[http/1.1]}{127.0.0.1:52563}
[junit4] 2> 1266386 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.Server Started @1266450ms
[junit4] 2> 1266386 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-002\collection1\data, hostPort=52563}
[junit4] 2> 1266387 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1266387 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1266387 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 1266387 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1266387 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1266387 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-27T12:33:59.321443600Z
[junit4] 2> 1266387 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-002\solr.xml
[junit4] 2> 1266389 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1266389 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1266390 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1266488 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1266488 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1266512 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1266524 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1266524 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1266530 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-002\.
[junit4] 2> 1266530 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1266537 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 1266551 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1266552 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1266552 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-002\.\collection1, trusted=true
[junit4] 2> 1266553 INFO (coreLoadExecutor-6980-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@62a6fba6
[junit4] 2> 1266553 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1266553 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1266554 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6378175394632903]
[junit4] 2> 1266620 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1266620 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1266621 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1266621 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d1159ee[collection1] main]
[junit4] 2> 1266621 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-002\collection1\conf
[junit4] 2> 1266623 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1266623 INFO (coreLoadExecutor-6980-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1266623 INFO (searcherExecutor-6981-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d1159ee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1266626 INFO (qtp2057599955-15694) [ 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> 1266628 INFO (qtp2057599955-15689) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 1266629 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1266629 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@72824851 commitCommandVersion:0
[junit4] 2> 1266630 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@28282e11[collection1] main]
[junit4] 2> 1266630 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1266631 INFO (searcherExecutor-6968-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@28282e11[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1)))}
[junit4] 2> 1266632 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 1266635 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1266636 INFO (qtp2057599955-15693) [ 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> 1266637 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1266637 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1532694839563
[junit4] 2> 1266637 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1266637 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1266637 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1266641 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=4
[junit4] 2> 1266642 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 1266643 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2041875459, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1266643 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1266643 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@dc1fcf8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@460f00bf)
[junit4] 2> 1266643 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266644 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266645 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266646 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266646 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266647 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266647 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266648 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266648 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266649 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266649 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 1815, Bytes skipped downloading: 0
[junit4] 2> 1266649 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1815) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@dc1fcf8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@460f00bf)
[junit4] 2> 1266656 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=15.9921875, floorSegmentMB=1.8740234375, forceMergeDeletesPctAllowed=27.90215735741601, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6378175394632903, deletesPctAllowed=37.03169089470454
[junit4] 2> 1266657 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1266657 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@53051874[collection1] main]
[junit4] 2> 1266658 INFO (searcherExecutor-6981-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@53051874[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1)))}
[junit4] 2> 1266658 INFO (qtp220831538-15715) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52560/solr/collection1/replication&command=fetchindex} status=0 QTime=25
[junit4] 2> 1266662 INFO (qtp220831538-15711) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1266663 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1266663 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1266664 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1266666 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1266667 INFO (qtp220831538-15714) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 1266667 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1266667 INFO (qtp220831538-15717) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1266668 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1266668 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1266668 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1266668 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 1266669 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1266670 INFO (qtp2057599955-15695) [ 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> 1266671 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1266671 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1532694839563
[junit4] 2> 1266671 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1266671 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1532694839563
[junit4] 2> 1266671 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 1266671 INFO (qtp220831538-15714) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52560/solr/collection1/replication&command=fetchindex} status=0 QTime=2
[junit4] 2> 1266672 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1266673 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1266673 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@72824851 commitCommandVersion:0
[junit4] 2> 1266674 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6dfd9c7b[collection1] main]
[junit4] 2> 1266674 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1266676 INFO (searcherExecutor-6968-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6dfd9c7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1) Uninverting(_1(7.5.0):C1)))}
[junit4] 2> 1266676 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1266678 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1266679 INFO (qtp2057599955-15693) [ 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> 1266679 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 1266679 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1532694839607
[junit4] 2> 1266679 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1266679 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1532694839563
[junit4] 2> 1266679 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1266680 INFO (qtp2057599955-15695) [ 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=1
[junit4] 2> 1266681 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 19
[junit4] 2> 1266682 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2041875459, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@311693e1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4844607f)
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tib because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tiv because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.doc because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 1266683 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 1266683 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266684 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266685 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266685 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266686 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266686 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266687 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266687 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266688 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266688 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1266688 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 1881, Bytes skipped downloading: 0
[junit4] 2> 1266688 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1881) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@311693e1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4844607f)
[junit4] 2> 1266691 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=15.9921875, floorSegmentMB=1.8740234375, forceMergeDeletesPctAllowed=27.90215735741601, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6378175394632903, deletesPctAllowed=37.03169089470454
[junit4] 2> 1266692 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1266692 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14091bc4[collection1] main]
[junit4] 2> 1266693 INFO (searcherExecutor-6981-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14091bc4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1) Uninverting(_1(7.5.0):C1)))}
[junit4] 2> 1266694 INFO (qtp220831538-15717) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52560/solr/collection1/replication&command=fetchindex} status=0 QTime=16
[junit4] 2> 1266695 INFO (qtp220831538-15714) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1266695 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1266695 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1266695 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1266697 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1266697 INFO (qtp220831538-15717) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1266698 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1266698 INFO (qtp220831538-15714) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1266699 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1266699 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1266699 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@72824851 commitCommandVersion:0
[junit4] 2> 1266700 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@77a25766[collection1] realtime]
[junit4] 2> 1266701 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1266701 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 1266702 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1266704 INFO (qtp2057599955-15693) [ 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> 1266704 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 1266704 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1532694839633
[junit4] 2> 1266704 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 1266704 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1532694839607
[junit4] 2> 1266704 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1266706 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 1266706 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 28
[junit4] 2> 1266707 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2041875459, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1266707 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1266707 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1266707 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1267707 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1267707 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1268708 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1268708 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1269708 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1269708 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1270708 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1270708 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1271709 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1271709 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1272709 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1272709 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1273710 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1273710 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1274710 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1274710 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1275710 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1275710 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1276711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1276711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index files to be delete-able
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@6c4bdd35 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78a154d)
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tib because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tiv because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.doc because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.doc because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tib because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
[junit4] 2> 1277711 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tiv because it already exists
[junit4] 2> 1277713 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277714 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277714 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277715 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277715 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277716 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277716 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277716 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277718 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277718 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277718 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 1947, Bytes skipped downloading: 0
[junit4] 2> 1277718 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1947) : 11 secs (177 bytes/sec) to MockDirectoryWrapper(RAMDirectory@6c4bdd35 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78a154d)
[junit4] 2> 1277719 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=15.9921875, floorSegmentMB=1.8740234375, forceMergeDeletesPctAllowed=27.90215735741601, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6378175394632903, deletesPctAllowed=37.03169089470454
[junit4] 2> 1277721 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1277721 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6cc6ec90[collection1] main]
[junit4] 2> 1277722 INFO (searcherExecutor-6981-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6cc6ec90[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1) Uninverting(_1(7.5.0):C1) Uninverting(_2(7.5.0):C1)))}
[junit4] 2> 1277722 INFO (qtp220831538-15717) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52560/solr/collection1/replication&command=fetchindex} status=0 QTime=11021
[junit4] 2> 1277725 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1277725 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 1277727 INFO (qtp220831538-15714) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1277728 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1277728 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1277729 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1277729 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@72824851 commitCommandVersion:0
[junit4] 2> 1277730 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@397f2c0e[collection1] main]
[junit4] 2> 1277731 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1277732 INFO (searcherExecutor-6968-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@397f2c0e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1) Uninverting(_1(7.5.0):C1) Uninverting(_2(7.5.0):C1) Uninverting(_3(7.5.0):C1)))}
[junit4] 2> 1277732 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1277733 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1277734 INFO (qtp2057599955-15695) [ 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> 1277735 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 5
[junit4] 2> 1277735 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1532694850663
[junit4] 2> 1277735 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 4
[junit4] 2> 1277735 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1532694839633
[junit4] 2> 1277735 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1277736 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 1277736 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 37
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2041875459, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@5865399b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d9b0b8c)
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tib because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tiv because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.doc because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.doc because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tib because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tiv because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2.nvd because it already exists
[junit4] 2> 1277738 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
[junit4] 2> 1277739 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2_LuceneVarGapFixedInterval_0.tib because it already exists
[junit4] 2> 1277739 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2.fdt because it already exists
[junit4] 2> 1277739 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2_LuceneVarGapFixedInterval_0.tiv because it already exists
[junit4] 2> 1277739 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2.fnm because it already exists
[junit4] 2> 1277739 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2.nvm because it already exists
[junit4] 2> 1277739 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2.fdx because it already exists
[junit4] 2> 1277739 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _2_LuceneVarGapFixedInterval_0.doc because it already exists
[junit4] 2> 1277739 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277740 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277740 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277741 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277742 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277742 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277743 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277744 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277744 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277745 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1277745 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 2013, Bytes skipped downloading: 0
[junit4] 2> 1277745 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2013) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@5865399b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d9b0b8c)
[junit4] 2> 1277747 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=15.9921875, floorSegmentMB=1.8740234375, forceMergeDeletesPctAllowed=27.90215735741601, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6378175394632903, deletesPctAllowed=37.03169089470454
[junit4] 2> 1277748 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1277749 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@36380b24[collection1] main]
[junit4] 2> 1277749 INFO (searcherExecutor-6981-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@36380b24[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C1) Uninverting(_1(7.5.0):C1) Uninverting(_2(7.5.0):C1) Uninverting(_3(7.5.0):C1)))}
[junit4] 2> 1277750 INFO (qtp220831538-15717) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:52560/solr/collection1/replication&command=fetchindex} status=0 QTime=16
[junit4] 2> 1277751 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=0
[junit4] 2> 1277752 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 1277752 INFO (qtp220831538-15714) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=0
[junit4] 2> 1277752 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 1277753 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1277753 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1277754 INFO (qtp2057599955-15693) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1277754 INFO (qtp220831538-15717) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1277755 INFO (qtp2057599955-15695) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1277755 INFO (qtp220831538-15714) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1277756 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7810aae9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1277756 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1298463702
[junit4] 2> 1277756 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1277756 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22c6bd56: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@488ffc62
[junit4] 2> 1277760 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1277760 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53aa0055: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4f6671ab
[junit4] 2> 1277762 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1277762 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a72d22c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@431619ee
[junit4] 2> 1277763 INFO (coreCloseExecutor-6990-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@426ba349
[junit4] 2> 1277763 INFO (coreCloseExecutor-6990-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=426ba349
[junit4] 2> 1277763 INFO (coreCloseExecutor-6990-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d915f4f: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@40b8196
[junit4] 2> 1277770 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5cc715cf{/solr,null,UNAVAILABLE}
[junit4] 2> 1277770 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1277772 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@71284ddd{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1277772 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1541979771
[junit4] 2> 1277773 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1277773 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4add2a13: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6417ea70
[junit4] 2> 1277778 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1277778 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3852cb1e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4f6671ab
[junit4] 2> 1277781 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1277781 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5493c49d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@431619ee
[junit4] 2> 1277781 INFO (coreCloseExecutor-6992-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6df3a3b5
[junit4] 2> 1277781 INFO (coreCloseExecutor-6992-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=6df3a3b5
[junit4] 2> 1277781 INFO (coreCloseExecutor-6992-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22095a40: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1f939cef
[junit4] 2> 1277792 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@45632058{/solr,null,UNAVAILABLE}
[junit4] 2> 1277792 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1277799 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10
[junit4] 2> 1277800 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1277800 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1277800 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1277800 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5cb01590{/solr,null,AVAILABLE}
[junit4] 2> 1277801 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5e96c4d3{HTTP/1.1,[http/1.1]}{127.0.0.1:52590}
[junit4] 2> 1277801 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.e.j.s.Server Started @1277864ms
[junit4] 2> 1277801 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-003\collection1\data, hostPort=52590}
[junit4] 2> 1277801 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1277801 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1277802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 1277802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1277802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1277802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-27T12:34:10.736329900Z
[junit4] 2> 1277802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-003\solr.xml
[junit4] 2> 1277805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1277805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1277805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1277997 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1277998 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1278021 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1278032 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1278032 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62a6fba6
[junit4] 2> 1278040 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-003\.
[junit4] 2> 1278040 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[41F1E1EB9DAD9C21]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1278042 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 1278083 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1278084 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1278084 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-003\.\collection1, trusted=true
[junit4] 2> 1278085 INFO (coreLoadExecutor-7002-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@62a6fba6
[junit4] 2> 1278085 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1278085 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-003\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_41F1E1EB9DAD9C21-001\solr-instance-003\.\collection1\data\]
[junit4] 2> 1278092 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6378175394632903]
[junit4] 2> 1278173 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1278173 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1278174 INFO (coreLoadExecutor-7002-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRati
[...truncated too long message...]
.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-authz-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-changelog-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-collective-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-event-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-exception-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-journal-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-normalization-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-operational-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-referral-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-schema-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-subtree-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-interceptors-trigger-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-jdbm-partition-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-jdbm1-2.0.0-M2.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-kerberos-codec-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-ldif-partition-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-mavibot-partition-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-protocol-kerberos-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-protocol-ldap-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-protocol-shared-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\apacheds-xdbm-partition-2.0.0-M15.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\api-all-1.0.0-M20.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\bcprov-jdk15on-1.54.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\byte-buddy-1.6.2.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\commons-collections-3.2.2.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\commons-math3-3.6.1.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\ehcache-core-2.4.4.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\hadoop-common-2.7.4-tests.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\hadoop-hdfs-2.7.4-tests.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\hadoop-minikdc-2.7.4.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\htrace-core-3.2.0-incubating.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\jersey-core-1.9.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\jersey-server-1.9.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\jetty-6.1.26.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\jetty-sslengine-6.1.26.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\jetty-util-6.1.26.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\mina-core-2.0.0-M5.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\mockito-core-2.6.2.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\netty-all-4.0.36.Final.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\test-lib\objenesis-2.5.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\analysis\icu\lucene-analyzers-icu-7.5.0-SNAPSHOT.jar;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\classes\java;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\queryparser\classes\test;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\classes\test;C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\contrib\analysis-extras\lib\icu4j-62.1.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-launcher.jar;C:\Users\jenkins\.ant\lib\ivy-2.4.0.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-antlr.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-bcel.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-bsf.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-log4j.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-oro.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-regexp.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-resolver.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-xalan2.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-commons-logging.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-commons-net.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jai.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-javamail.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jdepend.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jmf.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jsch.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-junit.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-junit4.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-netrexx.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-swing.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-testutil.jar;C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant.jar;C:\Users\jenkins\.ivy2\cache\com.carrotsearch.randomizedtesting\junit4-ant\jars\junit4-ant-2.6.0.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\temp\junit4-J0-20180727_121252_75616016473584027548365.events @C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\temp\junit4-J0-20180727_121252_75610717253447696164265.suites -stdin
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 1. Very likely a JVM crash. See process stdout at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\temp\junit4-J0-20180727_121252_7563670910398252922417.sysout
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[junit4] at java.base/java.lang.Thread.run(Thread.java:844)
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\build.xml:633: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\build.xml:577: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build.xml:267: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\common-build.xml:556: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1568: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1092: At least one slave process threw an exception, first: Forked process returned with error code: 1. Very likely a JVM crash. See process stdout at: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\temp\junit4-J0-20180727_121252_7563670910398252922417.sysout
Total time: 78 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2