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 2016/10/25 12:45:42 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_102) - Build #
6202 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6202/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseParallelGC
3 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=47411, name=SocketProxy-Response-55796:56285, state=RUNNABLE, group=TGRP-HttpPartitionTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=47411, name=SocketProxy-Response-55796:56285, state=RUNNABLE, group=TGRP-HttpPartitionTest]
at __randomizedtesting.SeedInfo.seed([2B3CBAB4DD1DC2C3:A368856E73E1AF3B]:0)
Caused by: java.lang.RuntimeException: java.net.SocketException: Socket is closed
at __randomizedtesting.SeedInfo.seed([2B3CBAB4DD1DC2C3]:0)
at org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:347)
Caused by: java.net.SocketException: Socket is closed
at java.net.Socket.setSoTimeout(Socket.java:1137)
at org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:344)
FAILED: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail
Error Message:
expected:<200> but was:<404>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<404>
at __randomizedtesting.SeedInfo.seed([2B3CBAB4DD1DC2C3:43838F9E0D87D02F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:303)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:383) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265) at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:383)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265)
at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([2B3CBAB4DD1DC2C3]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11562 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\init-core-data-001
[junit4] 2> 1790985 INFO (SUITE-TestReplicationHandler-seed#[2B3CBAB4DD1DC2C3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1790987 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestDetails
[junit4] 2> 1790988 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\collection1
[junit4] 2> 1791000 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1791000 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@59eaac3e{/solr,null,AVAILABLE}
[junit4] 2> 1791002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@233869d{HTTP/1.1,[http/1.1]}{127.0.0.1:56808}
[junit4] 2> 1791002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server Started @1795365ms
[junit4] 2> 1791002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=56808}
[junit4] 2> 1791002 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1791002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1791002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1791002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1791002 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-25T11:59:04.056Z
[junit4] 2> 1791003 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\solr.xml
[junit4] 2> 1791024 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\.
[junit4] 2> 1791025 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1791052 WARN (coreLoadExecutor-7436-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1791053 INFO (coreLoadExecutor-7436-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1791062 INFO (coreLoadExecutor-7436-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1791067 INFO (coreLoadExecutor-7436-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1791075 INFO (coreLoadExecutor-7436-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\.\collection1
[junit4] 2> 1791075 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 1791076 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1791088 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1791088 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1791089 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1791089 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@16623a14[collection1] main]
[junit4] 2> 1791089 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-001\collection1\conf
[junit4] 2> 1791090 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1791090 INFO (coreLoadExecutor-7436-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1791090 INFO (searcherExecutor-7437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16623a14[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1791538 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\collection1
[junit4] 2> 1791556 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1791557 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@53b2ac00{/solr,null,AVAILABLE}
[junit4] 2> 1791559 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@72ced6b2{HTTP/1.1,[http/1.1]}{127.0.0.1:56812}
[junit4] 2> 1791559 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server Started @1795921ms
[junit4] 2> 1791559 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=56812}
[junit4] 2> 1791559 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1791560 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1791560 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1791560 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1791560 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-25T11:59:04.614Z
[junit4] 2> 1791561 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\solr.xml
[junit4] 2> 1791582 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\.
[junit4] 2> 1791583 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1791607 WARN (coreLoadExecutor-7446-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1791607 INFO (coreLoadExecutor-7446-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1791627 INFO (coreLoadExecutor-7446-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1791630 INFO (coreLoadExecutor-7446-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1791630 INFO (coreLoadExecutor-7446-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\.\collection1
[junit4] 2> 1791635 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1791636 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1791648 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1791648 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1791648 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1791649 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d7c2eb2[collection1] main]
[junit4] 2> 1791649 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-002\collection1\conf
[junit4] 2> 1791653 INFO (coreLoadExecutor-7446-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1791654 INFO (searcherExecutor-7447-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d7c2eb2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1791664 INFO (qtp11984738-15696) [ 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> 1791665 INFO (indexFetcher-7451-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1791665 INFO (indexFetcher-7451-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1791665 INFO (indexFetcher-7451-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1792086 INFO (qtp11984738-15699) [ 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> 1792088 INFO (qtp11984738-15702) [ 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> 1792092 INFO (qtp11984738-15701) [ 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> 1792092 INFO (qtp1418359614-15715) [ 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> 1792094 INFO (qtp11984738-15703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1
[junit4] 2> 1792094 INFO (qtp11984738-15700) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1792094 INFO (qtp11984738-15700) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6341e415
[junit4] 2> 1792098 INFO (qtp11984738-15700) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@62a83356[collection1] main]
[junit4] 2> 1792098 INFO (qtp11984738-15700) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1792098 INFO (searcherExecutor-7437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@62a83356[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 1792098 INFO (qtp11984738-15700) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1792101 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1792102 INFO (qtp11984738-15696) [ 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> 1792103 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1792103 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477396745148
[junit4] 2> 1792103 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1792103 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1792106 INFO (qtp11984738-15698) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=3
[junit4] 2> 1792107 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 1792107 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=58.7880859375, floorSegmentMB=0.7802734375, forceMergeDeletesPctAllowed=8.63387432087005, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1792109 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1792109 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@346d5961 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cb3d323)
[junit4] 2> 1792110 INFO (qtp11984738-15699) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1792110 INFO (qtp11984738-15702) [ 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> 1792111 INFO (qtp11984738-15701) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1792112 INFO (qtp11984738-15703) [ 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> 1792112 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1987) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@346d5961 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cb3d323)
[junit4] 2> 1792114 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=90.337890625, floorSegmentMB=1.64453125, forceMergeDeletesPctAllowed=8.245771224619155, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.555076032254619
[junit4] 2> 1792115 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1792116 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@513c5bbc[collection1] main]
[junit4] 2> 1792116 INFO (searcherExecutor-7447-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@513c5bbc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 1792116 INFO (qtp1418359614-15717) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56808/solr/collection1/replication&command=fetchindex} status=0 QTime=16
[junit4] 2> 1792127 INFO (qtp11984738-15700) [ 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> 1792127 INFO (qtp1418359614-15718) [ 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=2
[junit4] 2> 1792129 INFO (qtp11984738-15696) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 1792130 INFO (qtp11984738-15698) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1792130 INFO (qtp11984738-15698) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6341e415
[junit4] 2> 1792132 INFO (qtp11984738-15698) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@56a4e73[collection1] main]
[junit4] 2> 1792133 INFO (qtp11984738-15698) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1792133 INFO (searcherExecutor-7437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@56a4e73[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 1792133 INFO (qtp11984738-15698) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1792134 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1792135 INFO (qtp11984738-15699) [ 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> 1792136 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 1792136 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477396745184
[junit4] 2> 1792136 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1792136 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1792137 INFO (qtp11984738-15702) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
[junit4] 2> 1792137 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7
[junit4] 2> 1792138 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=58.7880859375, floorSegmentMB=0.7802734375, forceMergeDeletesPctAllowed=8.63387432087005, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1792138 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1792139 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@50e50751 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@553241e4)
[junit4] 2> 1792139 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 1792139 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1792139 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 1792139 INFO (qtp11984738-15701) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1792142 INFO (qtp11984738-15703) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1792143 INFO (qtp11984738-15700) [ 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> 1792145 INFO (qtp11984738-15696) [ 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> 1792146 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2050) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@50e50751 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@553241e4)
[junit4] 2> 1792147 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics
[junit4] 2> java.io.IOException: file "replication.properties" was already written to
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:689)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1792148 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=90.337890625, floorSegmentMB=1.64453125, forceMergeDeletesPctAllowed=8.245771224619155, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.555076032254619
[junit4] 2> 1792149 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1792149 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@339b9262[collection1] main]
[junit4] 2> 1792150 INFO (searcherExecutor-7447-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@339b9262[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 1792150 INFO (qtp1418359614-15719) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56808/solr/collection1/replication&command=fetchindex} status=0 QTime=16
[junit4] 2> 1792153 INFO (qtp11984738-15698) [ 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> 1792153 INFO (qtp1418359614-15720) [ 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> 1792154 INFO (qtp11984738-15699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1792154 INFO (qtp11984738-15699) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1792156 INFO (qtp11984738-15699) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6341e415
[junit4] 2> 1792158 INFO (qtp11984738-15699) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c003b82[collection1] main]
[junit4] 2> 1792158 INFO (qtp11984738-15699) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1792158 INFO (searcherExecutor-7437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c003b82[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 1792158 INFO (qtp11984738-15699) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1792160 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1792161 INFO (qtp11984738-15702) [ 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> 1792161 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 1792161 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477396745210
[junit4] 2> 1792161 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 1792161 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1792162 INFO (qtp11984738-15701) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
[junit4] 2> 1792163 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 1792163 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=58.7880859375, floorSegmentMB=0.7802734375, forceMergeDeletesPctAllowed=8.63387432087005, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@7db29272 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c0af56d)
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 1792164 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 1792164 INFO (qtp11984738-15703) [ 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> 1792166 INFO (qtp11984738-15700) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1792167 INFO (qtp11984738-15696) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 1792167 INFO (qtp11984738-15698) [ 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> 1792168 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2113) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@7db29272 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c0af56d)
[junit4] 2> 1792170 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics
[junit4] 2> java.io.IOException: file "replication.properties" was already written to
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:689)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1792171 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=90.337890625, floorSegmentMB=1.64453125, forceMergeDeletesPctAllowed=8.245771224619155, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.555076032254619
[junit4] 2> 1792172 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1792172 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@56a9d9e0[collection1] main]
[junit4] 2> 1792173 INFO (searcherExecutor-7447-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@56a9d9e0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 1792173 INFO (qtp1418359614-15713) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:56808/solr/collection1/replication&command=fetchindex} status=0 QTime=14
[junit4] 2> 1792177 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\collection1
[junit4] 2> 1792186 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1792188 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@720277e0{/solr,null,AVAILABLE}
[junit4] 2> 1792189 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3a1bad5d{HTTP/1.1,[http/1.1]}{127.0.0.1:56819}
[junit4] 2> 1792189 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server Started @1796551ms
[junit4] 2> 1792189 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\collection1\data, hostContext=/solr, hostPort=56819}
[junit4] 2> 1792189 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1792189 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1792190 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1792190 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1792190 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-25T11:59:05.244Z
[junit4] 2> 1792191 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\solr.xml
[junit4] 2> 1792207 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\.
[junit4] 2> 1792207 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1792224 WARN (coreLoadExecutor-7460-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1792225 INFO (coreLoadExecutor-7460-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1792235 INFO (coreLoadExecutor-7460-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1792238 INFO (coreLoadExecutor-7460-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1792243 INFO (coreLoadExecutor-7460-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\.\collection1
[junit4] 2> 1792243 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\.\collection1\data\]
[junit4] 2> 1792244 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1792253 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1792253 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1792253 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1792253 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@593c5280[collection1] main]
[junit4] 2> 1792254 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-003\collection1\conf
[junit4] 2> 1792254 WARN (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1792255 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1792255 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 1792256 INFO (coreLoadExecutor-7460-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1792256 INFO (searcherExecutor-7461-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@593c5280[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1792654 INFO (qtp11984738-15699) [ 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> 1792655 INFO (indexFetcher-7451-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 1792655 INFO (indexFetcher-7451-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1477396745210
[junit4] 2> 1792655 INFO (indexFetcher-7451-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4
[junit4] 2> 1792655 INFO (indexFetcher-7451-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 1792713 WARN (qtp916778742-15743) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master
[junit4] 2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56808/solr: Expected mime type application/octet-stream but got text/html. <html>
[junit4] 2> <head>
[junit4] 2> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
[junit4] 2> <title>Error 404 </title>
[junit4] 2> </head>
[junit4] 2> <body>
[junit4] 2> <h2>HTTP ERROR: 404</h2>
[junit4] 2> <p>Problem accessing /solr/replication. Reason:
[junit4] 2> <pre> Can not find: /solr/replication</pre></p>
[junit4] 2> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.8.v20160314</a><hr/>
[junit4] 2> </body>
[junit4] 2> </html>
[junit4] 2>
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:578)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1726)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:938)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:153)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2229)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:111)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1792714 INFO (qtp916778742-15743) [ 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=2
[junit4] 2> 1792715 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@3a1bad5d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1792715 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=422380511
[junit4] 2> 1792715 INFO (coreCloseExecutor-7465-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50a9ac54
[junit4] 2> 1792716 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@720277e0{/solr,null,UNAVAILABLE}
[junit4] 2> 1792718 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestDetails
[junit4] 2> 1792719 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@233869d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1792719 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=154038845
[junit4] 2> 1792720 INFO (coreCloseExecutor-7467-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4c686a08
[junit4] 2> 1792720 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@59eaac3e{/solr,null,UNAVAILABLE}
[junit4] 2> 1792722 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@72ced6b2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1792722 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1702401992
[junit4] 2> 1792723 INFO (coreCloseExecutor-7469-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6e5d2cb
[junit4] 2> 1792724 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@53b2ac00{/solr,null,UNAVAILABLE}
[junit4] 2> 1792727 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartup
[junit4] 2> 1792729 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\collection1
[junit4] 2> 1792740 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1792741 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c6654ed{/solr,null,AVAILABLE}
[junit4] 2> 1792743 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@75873f82{HTTP/1.1,[http/1.1]}{127.0.0.1:56825}
[junit4] 2> 1792743 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server Started @1797106ms
[junit4] 2> 1792743 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\collection1\data, hostContext=/solr, hostPort=56825}
[junit4] 2> 1792743 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1792744 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1792744 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1792744 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1792744 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-25T11:59:05.798Z
[junit4] 2> 1792745 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\solr.xml
[junit4] 2> 1792764 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\.
[junit4] 2> 1792764 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1792784 WARN (coreLoadExecutor-7476-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1792785 INFO (coreLoadExecutor-7476-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1792800 INFO (coreLoadExecutor-7476-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1792805 INFO (coreLoadExecutor-7476-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1792812 INFO (coreLoadExecutor-7476-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\.\collection1
[junit4] 2> 1792812 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\.\collection1\data\]
[junit4] 2> 1792812 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1792824 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1792824 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1792824 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1792824 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f84e6c9[collection1] main]
[junit4] 2> 1792824 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\collection1\conf
[junit4] 2> 1792825 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1792825 INFO (coreLoadExecutor-7476-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1792825 INFO (searcherExecutor-7477-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f84e6c9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1793268 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\collection1
[junit4] 2> 1793277 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1793278 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13b67de7{/solr,null,AVAILABLE}
[junit4] 2> 1793279 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@28098993{HTTP/1.1,[http/1.1]}{127.0.0.1:56828}
[junit4] 2> 1793279 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server Started @1797642ms
[junit4] 2> 1793279 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\collection1\data, hostContext=/solr, hostPort=56828}
[junit4] 2> 1793279 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1793279 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1793279 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1793279 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1793280 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-25T11:59:06.333Z
[junit4] 2> 1793280 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\solr.xml
[junit4] 2> 1793292 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\.
[junit4] 2> 1793292 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1793304 WARN (coreLoadExecutor-7486-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1793305 INFO (coreLoadExecutor-7486-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1793314 INFO (coreLoadExecutor-7486-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1793316 INFO (coreLoadExecutor-7486-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1793319 INFO (coreLoadExecutor-7486-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\.\collection1
[junit4] 2> 1793319 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\.\collection1\data\]
[junit4] 2> 1793320 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1793327 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1793327 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1793328 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1793328 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@68ab27d1[collection1] main]
[junit4] 2> 1793328 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-005\collection1\conf
[junit4] 2> 1793330 INFO (coreLoadExecutor-7486-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1793330 INFO (searcherExecutor-7487-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68ab27d1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1793333 INFO (qtp1519396340-15766) [ 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> 1793334 INFO (indexFetcher-7491-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1793334 INFO (indexFetcher-7491-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1793334 INFO (indexFetcher-7491-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1793795 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@28098993{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1793796 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1667586265
[junit4] 2> 1793796 INFO (coreCloseExecutor-7492-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1286f4e7
[junit4] 2> 1793798 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@13b67de7{/solr,null,UNAVAILABLE}
[junit4] 2> 1793800 INFO (qtp1519396340-15769) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1793800 INFO (qtp1519396340-15769) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1793801 INFO (qtp1519396340-15768) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1793801 INFO (qtp1519396340-15768) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b7fe579
[junit4] 2> 1793803 INFO (qtp1519396340-15768) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5485025d[collection1] main]
[junit4] 2> 1793803 INFO (qtp1519396340-15768) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1793803 INFO (searcherExecutor-7477-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5485025d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1793803 INFO (qtp1519396340-15768) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 1793805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@75873f82{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1793805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=469758776
[junit4] 2> 1793806 INFO (coreCloseExecutor-7494-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3bfc7e8a
[junit4] 2> 1793807 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2c6654ed{/solr,null,UNAVAILABLE}
[junit4] 2> 1793809 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1793810 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42ca8296{/solr,null,AVAILABLE}
[junit4] 2> 1793811 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5f36a4af{HTTP/1.1,[http/1.1]}{127.0.0.1:56834}
[junit4] 2> 1793811 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.e.j.s.Server Started @1798173ms
[junit4] 2> 1793811 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\collection1\data, hostContext=/solr, hostPort=56834}
[junit4] 2> 1793811 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1793811 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1793811 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1793811 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1793811 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-25T11:59:06.865Z
[junit4] 2> 1793812 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\solr.xml
[junit4] 2> 1793820 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\.
[junit4] 2> 1793820 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1793828 WARN (coreLoadExecutor-7501-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1793829 INFO (coreLoadExecutor-7501-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1793844 INFO (coreLoadExecutor-7501-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1793847 INFO (coreLoadExecutor-7501-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1793848 INFO (coreLoadExecutor-7501-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\.\collection1
[junit4] 2> 1793848 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\.\collection1\data\]
[junit4] 2> 1793849 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1793855 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1793855 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1793855 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1793855 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@785b5305[collection1] main]
[junit4] 2> 1793856 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_2B3CBAB4DD1DC2C3-001\solr-instance-004\collection1\conf
[junit4] 2> 1793856 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 1793856 INFO (coreLoadExecutor-7501-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1793856 INFO (searcherExecutor-7502-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@785b5305[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1794326 INFO (qtp788630737-15803) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 1794326 INFO (qtp788630737-15804) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1794327 INFO (qtp788630737-15805) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1794327 INFO (qtp788630737-15806) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1794327 INFO (qtp788630737-15807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1794328 INFO (qtp788630737-15808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1794328 INFO (qtp788630737-15801) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1794328 INFO (qtp788630737-15803) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1794329 INFO (qtp788630737-15804) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1794329 INFO (qtp788630737-15805) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1794330 INFO (qtp788630737-15806) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1794330 INFO (qtp788630737-15807) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1794331 INFO (qtp788630737-15808) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1794331 INFO (qtp788630737-15801) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=java
[...truncated too long message...]
0x157fbbe3cd00005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2510511 INFO (TEST-HttpPartitionTest.test-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 55766
[junit4] 2> 2510512 INFO (TEST-HttpPartitionTest.test-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55757 55757
[junit4] 2> 2510514 INFO (Thread-3405) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55757 55757
[junit4] 2> 2510515 WARN (Thread-3405) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2> 4 /solr/collections/collMinRf_1x3/state.json
[junit4] 2> 3 /solr/collections/c8n_1x3/state.json
[junit4] 2> 3 /solr/collections/c8n_1x2_leader_session_loss/state.json
[junit4] 2> 2 /solr/collections/c8n_1x2/state.json
[junit4] 2> 2 /solr/collections/c8n_crud_1x2/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/96822711494377480-core_node1-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/96822711494377483-127.0.0.1:55795_ka%2Fqu-n_0000000002
[junit4] 2> 2 /solr/collections/collMinRf_1x3/leader_elect/shard1/election/96822711494377483-core_node1-n_0000000001
[junit4] 2> 2 /solr/collections/c8n_1x3/leader_elect/shard1/election/96822711494377480-core_node1-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 130 /solr/overseer/queue
[junit4] 2> 112 /solr/overseer/collection-queue-work
[junit4] 2> 15 /solr/overseer/queue-work
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2>
[junit4] 2> 2510516 WARN (TEST-HttpPartitionTest.test-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SocketProxy Closing 14 connections to: http://127.0.0.1:55795/ka/qu, target: http://127.0.0.1:55796/ka/qu
[junit4] 2> 2510516 WARN (TEST-HttpPartitionTest.test-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SocketProxy Closing 15 connections to: http://127.0.0.1:55782/ka/qu, target: http://127.0.0.1:55783/ka/qu
[junit4] 2> 2510516 WARN (TEST-HttpPartitionTest.test-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SocketProxy Closing 11 connections to: http://127.0.0.1:55766/ka/qu, target: http://127.0.0.1:55767/ka/qu
[junit4] 2> 2510517 WARN (TEST-HttpPartitionTest.test-seed#[2B3CBAB4DD1DC2C3]) [ ] o.a.s.c.SocketProxy Closing 7 connections to: http://127.0.0.1:55808/ka/qu, target: http://127.0.0.1:55809/ka/qu
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionTest -Dtests.method=test -Dtests.seed=2B3CBAB4DD1DC2C3 -Dtests.slow=true -Dtests.locale=en-NZ -Dtests.timezone=Turkey -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 132s J0 | HttpPartitionTest.test <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=47411, name=SocketProxy-Response-55796:56285, state=RUNNABLE, group=TGRP-HttpPartitionTest]
[junit4] > at __randomizedtesting.SeedInfo.seed([2B3CBAB4DD1DC2C3:A368856E73E1AF3B]:0)
[junit4] > Caused by: java.lang.RuntimeException: java.net.SocketException: Socket is closed
[junit4] > at __randomizedtesting.SeedInfo.seed([2B3CBAB4DD1DC2C3]:0)
[junit4] > at org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:347)
[junit4] > Caused by: java.net.SocketException: Socket is closed
[junit4] > at java.net.Socket.setSoTimeout(Socket.java:1137)
[junit4] > at org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:344)
[junit4] 2> 2510521 INFO (SUITE-HttpPartitionTest-seed#[2B3CBAB4DD1DC2C3]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> Oct 25, 2016 12:11:03 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=29236, maxDocsPerChunk=362, blockSize=855), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=29236, blockSize=855)), sim=ClassicSimilarity, locale=en-NZ, timezone=Turkey
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=248391896,total=524812288
[junit4] 2> NOTE: All tests run in this JVM: [SimpleFacetsTest, ZkCLITest, TestJettySolrRunner, TestConfigSetsAPIZkFailure, TestSort, ConnectionManagerTest, TestDefaultSearchFieldResource, DistanceUnitsTest, MigrateRouteKeyTest, TestTolerantSearch, TestMacroExpander, TestConfigSetsAPI, TestLRUStatsCache, TestLRUCache, CdcrReplicationDistributedZkTest, CursorPagingTest, TestNoOpRegenerator, CustomCollectionTest, TestRecoveryHdfs, DocumentAnalysisRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, TestDFISimilarityFactory, MergeStrategyTest, TestConfigOverlay, SolrIndexConfigTest, FileUtilsTest, FullHLLTest, SyncSliceTest, TestBinaryResponseWriter, PeerSyncTest, TestRandomRequestDistribution, StatsReloadRaceTest, SearchHandlerTest, TestFieldCacheSortRandom, TestCloudPivotFacet, TestSQLHandler, StandardRequestHandlerTest, TestFunctionQuery, LeaderFailoverAfterPartitionTest, TestReqParamsAPI, TestSchemaResource, TestSolrQueryParserDefaultOperatorResource, TestGeoJSONResponseWriter, ShardSplitTest, DistributedQueryElevationComponentTest, TestFieldResource, CloudExitableDirectoryReaderTest, OpenExchangeRatesOrgProviderTest, AnalysisAfterCoreReloadTest, HLLUtilTest, TestPKIAuthenticationPlugin, BaseCdcrDistributedZkTest, TestCustomStream, TestUseDocValuesAsStored2, TestSolrCoreSnapshots, UniqFieldsUpdateProcessorFactoryTest, TestExactSharedStatsCache, SuggestComponentContextFilterQueryTest, TestPseudoReturnFields, TestSchemaVersionResource, TestPerFieldSimilarity, TestPartialUpdateDeduplication, OverseerCollectionConfigSetProcessorTest, TestSolrConfigHandlerCloud, TestPivotHelperCode, DistributedQueryComponentOptimizationTest, TestHashPartitioner, TestMaxScoreQueryParser, PKIAuthenticationIntegrationTest, TestJmxIntegration, SchemaVersionSpecificBehaviorTest, CollectionStateFormat2Test, TestSolrQueryResponse, DirectSolrSpellCheckerTest, TestLFUCache, TestDynamicFieldResource, HdfsChaosMonkeyNothingIsSafeTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestIBSimilarityFactory, SharedFSAutoReplicaFailoverUtilsTest, QueryElevationComponentTest, TestMinMaxOnMultiValuedField, CdcrRequestHandlerTest, DirectUpdateHandlerTest, TestFieldCache, DistributedFacetPivotLargeTest, TestWordDelimiterFilterFactory, DocExpirationUpdateProcessorFactoryTest, DocValuesTest, SoftAutoCommitTest, TestTolerantUpdateProcessorRandomCloud, TestFaceting, TestSolrFieldCacheMBean, DirectSolrConnectionTest, TestFieldSortValues, TestBinaryField, ClassificationUpdateProcessorFactoryTest, TestFieldCacheSanityChecker, CollectionsAPIAsyncDistributedZkTest, TestDistributedSearch, CurrencyFieldXmlFileTest, TestMergePolicyConfig, SuggesterFSTTest, TermsComponentTest, TestHdfsBackupRestoreCore, TestNumericTerms32, PluginInfoTest, DistributedExpandComponentTest, DOMUtilTest, TestOnReconnectListenerSupport, ZkStateReaderTest, TestBulkSchemaConcurrent, RequiredFieldsTest, RestartWhileUpdatingTest, RecoveryZkTest, TestRTimerTree, TestRequestStatusCollectionAPI, TestConfigSetProperties, TestPostingsSolrHighlighter, OverseerTaskQueueTest, SolrPluginUtilsTest, BasicDistributedZkTest, DistributedQueueTest, TestExclusionRuleCollectionAccess, TestComplexPhraseQParserPlugin, TestExtendedDismaxParser, TestInitQParser, CoreAdminCreateDiscoverTest, ConcurrentDeleteAndCreateCollectionTest, SSLMigrationTest, TestSolrCloudWithDelegationTokens, TestImplicitCoreProperties, HardAutoCommitTest, BigEndianAscendingWordDeserializerTest, TestQueryWrapperFilter, TestDocumentBuilder, AnalyticsQueryTest, OutputWriterTest, TestStressLucene, TestQuerySenderNoQuery, MultiTermTest, DeleteStatusTest, TestJsonRequest, TestDistributedStatsComponentCardinality, SampleTest, CoreMergeIndexesAdminHandlerTest, TestManagedSynonymFilterFactory, TestLockTree, ZkControllerTest, TestXIncludeConfig, TestPhraseSuggestions, TestStressLiveNodes, TestCSVResponseWriter, TriLevelCompositeIdRoutingTest, PreAnalyzedFieldTest, PrimUtilsTest, TestSuggestSpellingConverter, DateFieldTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, CircularListTest, DistributedMLTComponentTest, TestRTGBase, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestEmbeddedSolrServerConstructors, ActionThrottleTest, AliasIntegrationTest, CdcrReplicationHandlerTest, CdcrVersionReplicationTest, ConfigSetsAPITest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteReplicaTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, HttpPartitionTest]
[junit4] Completed [469/642 (3!)] on J0 in 132.92s, 1 test, 1 error <<< FAILURES!
[...truncated 60926 lines...]