[JENKINS] Lucene-Solr-Tests-master - Build # 1634 - Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1634/

1 tests failed.
FAILED:  org.apache.solr.handler.TestReplicationHandler.doTestDetails

Error Message:


Stack Trace:
java.lang.NullPointerException
        at 
__randomizedtesting.SeedInfo.seed([B6D340F54DF7ED2F:CC8E6380DBAFC4A7]:0)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestDetails(TestReplicationHandler.java:312)
        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:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java: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:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11866 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/init-core-data-001
   [junit4]   2> 1171666 INFO  
(SUITE-TestReplicationHandler-seed#[B6D340F54DF7ED2F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1171668 INFO  
(SUITE-TestReplicationHandler-seed#[B6D340F54DF7ED2F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4] IGNOR/A 0.00s J2 | 
TestReplicationHandler.doTestIndexFetchOnMasterRestart
   [junit4]    > Assumption #1: 'awaitsfix' test group is disabled 
(@AwaitsFix(bugUrl=https://issues.apache.org/jira/browse/SOLR-9036))
   [junit4]   2> 1171681 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
   [junit4]   2> 1171682 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1
   [junit4]   2> 1171689 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1171693 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@42eca012{/solr,null,AVAILABLE}
   [junit4]   2> 1171694 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@326a535b{HTTP/1.1,[http/1.1]}{127.0.0.1:49592}
   [junit4]   2> 1171694 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.Server Started @1175696ms
   [junit4]   2> 1171694 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=49592}
   [junit4]   2> 1171694 ERROR 
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1171694 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1171694 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1171694 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1171694 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-24T03:28:02.484Z
   [junit4]   2> 1171695 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/solr.xml
   [junit4]   2> 1171716 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/.
   [junit4]   2> 1171716 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1171737 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1171747 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1171751 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1171752 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/./collection1
   [junit4]   2> 1171753 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 1171755 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, 
floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1171765 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1171765 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1171766 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, 
floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1171766 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@74d56046[collection1] main]
   [junit4]   2> 1171767 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-001/collection1/conf
   [junit4]   2> 1171767 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1171767 INFO  (coreLoadExecutor-4381-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1171768 INFO  
(searcherExecutor-4382-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@74d56046[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1172524 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1
   [junit4]   2> 1172527 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1172528 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2d7a2e4e{/solr,null,AVAILABLE}
   [junit4]   2> 1172528 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7d2df843{HTTP/1.1,[http/1.1]}{127.0.0.1:34472}
   [junit4]   2> 1172528 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.Server Started @1176531ms
   [junit4]   2> 1172529 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1/data,
 hostContext=/solr, hostPort=34472}
   [junit4]   2> 1172529 ERROR 
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1172529 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1172529 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1172529 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1172529 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-24T03:28:03.319Z
   [junit4]   2> 1172530 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/solr.xml
   [junit4]   2> 1172544 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/.
   [junit4]   2> 1172544 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1172560 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1172568 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1172570 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1172571 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/./collection1
   [junit4]   2> 1172572 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 1172573 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, 
floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1172582 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1172582 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1172582 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, 
floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1172583 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@53e84929[collection1] main]
   [junit4]   2> 1172583 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-002/collection1/conf
   [junit4]   2> 1172585 INFO  (coreLoadExecutor-4391-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 1172585 INFO  
(searcherExecutor-4392-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@53e84929[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1172588 INFO  (qtp1803179718-8704) [    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> 1172589 INFO  (indexFetcher-4396-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 1172589 INFO  (indexFetcher-4396-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 1172589 INFO  (indexFetcher-4396-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1173331 INFO  (qtp1803179718-8705) [    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> 1173333 INFO  (qtp1803179718-8705) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 1
   [junit4]   2> 1173334 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1173334 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
   [junit4]   2> 1173339 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1e458675[collection1] main]
   [junit4]   2> 1173339 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1173339 INFO  
(searcherExecutor-4382-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e458675[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 1173339 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 5
   [junit4]   2> 1173343 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1173344 INFO  (qtp1803179718-8707) [    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> 1173344 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1173344 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485228484124
   [junit4]   2> 1173344 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1173344 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1173347 INFO  (qtp1803179718-8703) [    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=2
   [junit4]   2> 1173347 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 4
   [junit4]   2> 1173348 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1173351 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1173351 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@3fe06969 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@912194f))
   [junit4]   2> 1173352 INFO  (qtp1803179718-8704) [    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> 1173353 INFO  (qtp1803179718-8708) [    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> 1173354 INFO  (qtp1803179718-8705) [    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> 1173357 INFO  (qtp1803179718-8709) [    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> 1173358 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2329) : 0 secs (null bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@3fe06969 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@912194f))
   [junit4]   2> 1173359 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, 
floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49056111239663813
   [junit4]   2> 1173361 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1173362 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5ebed524[collection1] main]
   [junit4]   2> 1173362 INFO  
(searcherExecutor-4392-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ebed524[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 1173363 INFO  (qtp1941550639-8723) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex}
 status=0 QTime=21
   [junit4]   2> 1173368 INFO  (qtp1941550639-8725) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=1
   [junit4]   2> 1173368 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 1173369 INFO  (qtp1803179718-8710) [    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> 1173370 INFO  (qtp1803179718-8703) [    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> 1173371 INFO  (qtp1803179718-8707) [    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> 1173372 INFO  (qtp1941550639-8725) [    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> 1173372 INFO  (qtp1803179718-8703) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1173373 INFO  (qtp1941550639-8727) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1173373 INFO  (qtp1803179718-8705) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1173373 INFO  (qtp1803179718-8705) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1173373 INFO  (qtp1803179718-8705) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1173374 INFO  (qtp1803179718-8705) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 0
   [junit4]   2> 1173377 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1173378 INFO  (qtp1803179718-8708) [    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> 1173378 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1173378 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485228484124
   [junit4]   2> 1173378 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 1173378 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 1173378 INFO  (qtp1941550639-8724) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex}
 status=0 QTime=4
   [junit4]   2> 1173380 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 1
   [junit4]   2> 1173381 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1173381 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
   [junit4]   2> 1173385 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@578ae5e[collection1] main]
   [junit4]   2> 1173385 INFO  
(searcherExecutor-4382-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@578ae5e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
 Uninverting(_1(7.0.0):c1)))}
   [junit4]   2> 1173385 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1173385 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 4
   [junit4]   2> 1173386 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1173387 INFO  (qtp1803179718-8708) [    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> 1173388 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 1173388 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485228484171
   [junit4]   2> 1173388 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 1173388 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1173389 INFO  (qtp1803179718-8708) [    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=1
   [junit4]   2> 1173390 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 7
   [junit4]   2> 1173391 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1173392 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1173393 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1e76f7fa 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d256b5a))
   [junit4]   2> 1173393 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
   [junit4]   2> 1173393 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1173393 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
   [junit4]   2> 1173393 INFO  (qtp1803179718-8705) [    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> 1173397 INFO  (qtp1803179718-8710) [    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> 1173398 INFO  (qtp1803179718-8709) [    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> 1173399 INFO  (qtp1803179718-8704) [    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> 1173399 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2392) : 0 secs (null bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1e76f7fa 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d256b5a))
   [junit4]   2> 1173401 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, 
floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49056111239663813
   [junit4]   2> 1173403 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1173405 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@71f54465[collection1] main]
   [junit4]   2> 1173405 INFO  
(searcherExecutor-4392-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@71f54465[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
 Uninverting(_1(7.0.0):c1)))}
   [junit4]   2> 1173405 INFO  (qtp1941550639-8723) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex}
 status=0 QTime=19
   [junit4]   2> 1173407 INFO  (qtp1941550639-8720) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 1173407 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 1173407 INFO  (qtp1803179718-8707) [    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> 1173408 INFO  (qtp1803179718-8708) [    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> 1173413 INFO  (qtp1803179718-8703) [    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> 1173414 INFO  (qtp1941550639-8726) [    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=4
   [junit4]   2> 1173414 INFO  (qtp1803179718-8705) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1173415 INFO  (qtp1941550639-8725) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1173415 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 1173416 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1173416 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
   [junit4]   2> 1173420 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@78be0591[collection1] realtime]
   [junit4]   2> 1173420 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1173420 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 3
   [junit4]   2> 1173421 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1173422 INFO  (qtp1803179718-8710) [    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> 1173422 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 4
   [junit4]   2> 1173422 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485228484206
   [junit4]   2> 1173422 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 1173422 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1173424 INFO  (qtp1803179718-8710) [    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=1
   [junit4]   2> 1173424 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 1173425 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1173427 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1173428 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1173428 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1174428 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1174428 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1175429 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1175429 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1176434 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1176434 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1177434 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1177434 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1178435 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1178435 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1179436 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1179436 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1180436 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1180437 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1181437 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1181437 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1182438 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1182438 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1183439 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1183439 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index 
files to be delete-able
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@2d8561c9 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@197167d9))
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
   [junit4]   2> 1184440 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 1184442 INFO  (qtp1803179718-8703) [    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> 1184443 INFO  (qtp1803179718-8705) [    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> 1184444 INFO  (qtp1803179718-8709) [    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> 1184445 INFO  (qtp1803179718-8704) [    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> 1184445 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2455) : 11 secs (223 bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@2d8561c9 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@197167d9))
   [junit4]   2> 1184447 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, 
floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49056111239663813
   [junit4]   2> 1184449 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1184452 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6a4aba8a[collection1] main]
   [junit4]   2> 1184452 INFO  
(searcherExecutor-4392-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a4aba8a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
 Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
   [junit4]   2> 1184452 INFO  (qtp1941550639-8721) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex}
 status=0 QTime=11031
   [junit4]   2> 1184455 INFO  (qtp1803179718-8707) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1184455 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
   [junit4]   2> 1184457 INFO  (qtp1941550639-8727) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 1184457 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 1184458 INFO  (qtp1803179718-8710) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 1184458 INFO  (qtp1803179718-8703) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184458 INFO  (qtp1803179718-8703) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7e5835b1
   [junit4]   2> 1184462 INFO  (qtp1803179718-8703) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d46402e[collection1] main]
   [junit4]   2> 1184462 INFO  (qtp1803179718-8703) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1184462 INFO  
(searcherExecutor-4382-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d46402e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
 Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1) 
Uninverting(_3(7.0.0):c1)))}
   [junit4]   2> 1184462 INFO  (qtp1803179718-8703) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 4
   [junit4]   2> 1184463 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1184464 INFO  (qtp1803179718-8708) [    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> 1184465 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 5
   [junit4]   2> 1184465 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485228495248
   [junit4]   2> 1184465 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 4
   [junit4]   2> 1184465 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1184466 INFO  (qtp1803179718-8709) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=1
   [junit4]   2> 1184467 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 13
   [junit4]   2> 1184468 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1184470 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@7dfe9a3a 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46f0cea3))
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.cfe because it already exists
   [junit4]   2> 1184471 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.cfs because it already exists
   [junit4]   2> 1184472 INFO  (qtp1803179718-8704) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1184473 INFO  (qtp1803179718-8707) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.cfs&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1184474 INFO  (qtp1803179718-8710) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.cfe&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1184474 INFO  (qtp1803179718-8703) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1184475 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2518) : 0 secs (null bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@7dfe9a3a 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46f0cea3))
   [junit4]   2> 1184476 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=51.6513671875, 
floorSegmentMB=1.93359375, forceMergeDeletesPctAllowed=13.855550891242732, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49056111239663813
   [junit4]   2> 1184479 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1184483 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3b329745[collection1] main]
   [junit4]   2> 1184483 INFO  
(searcherExecutor-4392-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3b329745[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)
 Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1) 
Uninverting(_3(7.0.0):c1)))}
   [junit4]   2> 1184483 INFO  (qtp1941550639-8724) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:49592/solr/collection1/replication&command=fetchindex}
 status=0 QTime=20
   [junit4]   2> 1184486 INFO  (qtp1803179718-8705) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} 
hits=4 status=0 QTime=1
   [junit4]   2> 1184486 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 1184487 INFO  (qtp1941550639-8723) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} 
hits=4 status=0 QTime=0
   [junit4]   2> 1184487 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 1184488 INFO  (qtp1803179718-8709) [    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> 1184489 INFO  (qtp1803179718-8704) [    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> 1184490 INFO  (qtp1803179718-8708) [    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> 1184490 INFO  (qtp1941550639-8726) [    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> 1184491 INFO  (qtp1803179718-8707) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1184492 INFO  (qtp1941550639-8725) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1184492 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
   [junit4]   2> 1184493 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@326a535b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1184493 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1302978184
   [junit4]   2> 1184494 INFO  (coreCloseExecutor-4401-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@41500bbb
   [junit4]   2> 1184494 INFO  (coreCloseExecutor-4401-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1184495 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1184495 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@42eca012{/solr,null,UNAVAILABLE}
   [junit4]   2> 1184496 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@7d2df843{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1184497 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1491076348
   [junit4]   2> 1184497 INFO  (coreCloseExecutor-4403-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4de9a5e5
   [junit4]   2> 1184498 INFO  (coreCloseExecutor-4403-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1184499 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1184499 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B6D340F54DF7ED2F]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2d7a2e4e{/solr,null,UNAVAILABLE}
   [junit4]   2> 1184501 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterWrite2Slave
   [junit4]   2> 1184502 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1
   [junit4]   2> 1184507 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1184508 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6469b350{/solr,null,AVAILABLE}
   [junit4]   2> 1184508 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.AbstractConnector Started 
ServerConnector@280b12b5{HTTP/1.1,[http/1.1]}{127.0.0.1:47033}
   [junit4]   2> 1184508 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.Server Started @1188511ms
   [junit4]   2> 1184508 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1/data,
 hostContext=/solr, hostPort=47033}
   [junit4]   2> 1184509 ERROR 
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1184509 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 7.0.0
   [junit4]   2> 1184509 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone 
mode on port null
   [junit4]   2> 1184509 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1184509 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-24T03:28:15.299Z
   [junit4]   2> 1184510 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/solr.xml
   [junit4]   2> 1184525 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/.
   [junit4]   2> 1184525 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1184538 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1184547 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1184562 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1184564 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/./collection1
   [junit4]   2> 1184564 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/./collection1/data/]
   [junit4]   2> 1184565 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, 
floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1184575 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1184575 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1184576 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, 
floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1184576 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3f6bde87[collection1] main]
   [junit4]   2> 1184576 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-003/collection1/conf
   [junit4]   2> 1184577 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1184577 INFO  (coreLoadExecutor-4410-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1184577 INFO  
(searcherExecutor-4411-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3f6bde87[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1185311 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1
   [junit4]   2> 1185314 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1185315 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5c4711ab{/solr,null,AVAILABLE}
   [junit4]   2> 1185316 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.AbstractConnector Started 
ServerConnector@7b6995aa{HTTP/1.1,[http/1.1]}{127.0.0.1:38527}
   [junit4]   2> 1185316 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.Server Started @1189318ms
   [junit4]   2> 1185316 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1/data,
 hostContext=/solr, hostPort=38527}
   [junit4]   2> 1185317 ERROR 
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1185317 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 7.0.0
   [junit4]   2> 1185317 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone 
mode on port null
   [junit4]   2> 1185317 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1185317 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-24T03:28:16.107Z
   [junit4]   2> 1185318 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/solr.xml
   [junit4]   2> 1185327 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/.
   [junit4]   2> 1185327 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1185335 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1185343 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1185345 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1185346 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/./collection1
   [junit4]   2> 1185347 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/./collection1/data/]
   [junit4]   2> 1185348 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=55.28515625, 
floorSegmentMB=1.9931640625, forceMergeDeletesPctAllowed=15.49552549920567, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1185357 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1185357 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1185357 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=2.068359375, 
floorSegmentMB=1.4716796875, forceMergeDeletesPctAllowed=25.0787188712178, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1185358 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b0d57b[collection1] 
main]
   [junit4]   2> 1185358 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001/solr-instance-004/collection1/conf
   [junit4]   2> 1185362 INFO  (coreLoadExecutor-4420-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 1185363 INFO  
(searcherExecutor-4421-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2b0d57b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1185366 INFO  (qtp439187683-8758) [    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> 1185366 INFO  (indexFetcher-4425-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 1185366 INFO  (indexFetcher-4425-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 1185366 INFO  (indexFetcher-4425-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1186116 INFO  (qtp439187683-8759) [    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> 1186118 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[0]} 0 0
   [junit4]   2> 1186118 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 1186119 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 1186119 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 1186120 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 1186120 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[5]} 0 0
   [junit4]   2> 1186120 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[6]} 0 0
   [junit4]   2> 1186121 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[7]} 0 0
   [junit4]   2> 1186121 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[8]} 0 0
   [junit4]   2> 1186122 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[9]} 0 0
   [junit4]   2> 1186129 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[10]} 0 7
   [junit4]   2> 1186134 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[11]} 0 0
   [junit4]   2> 1186134 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[12]} 0 0
   [junit4]   2> 1186134 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[13]} 0 0
   [junit4]   2> 1186135 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[14]} 0 0
   [junit4]   2> 1186135 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[15]} 0 0
   [junit4]   2> 1186136 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[16]} 0 0
   [junit4]   2> 1186136 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[17]} 0 0
   [junit4]   2> 1186137 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[18]} 0 0
   [junit4]   2> 1186137 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[19]} 0 0
   [junit4]   2> 1186138 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[20]} 0 0
   [junit4]   2> 1186141 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[21]} 0 2
   [junit4]   2> 1186141 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[22]} 0 0
   [junit4]   2> 1186142 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[23]} 0 0
   [junit4]   2> 1186142 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[24]} 0 0
   [junit4]   2> 1186143 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[25]} 0 0
   [junit4]   2> 1186143 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[26]} 0 0
   [junit4]   2> 1186144 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[27]} 0 0
   [junit4]   2> 1186144 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[28]} 0 0
   [junit4]   2> 1186144 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[29]} 0 0
   [junit4]   2> 1186145 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[30]} 0 0
   [junit4]   2> 1186148 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[31]} 0 0
   [junit4]   2> 1186151 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[32]} 0 2
   [junit4]   2> 1186152 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[33]} 0 0
   [junit4]   2> 1186153 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[34]} 0 0
   [junit4]   2> 1186153 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[35]} 0 0
   [junit4]   2> 1186154 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[36]} 0 0
   [junit4]   2> 1186154 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[37]} 0 0
   [junit4]   2> 1186154 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[38]} 0 0
   [junit4]   2> 1186155 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[39]} 0 0
   [junit4]   2> 1186155 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[40]} 0 0
   [junit4]   2> 1186156 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[41]} 0 0
   [junit4]   2> 1186156 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[42]} 0 0
   [junit4]   2> 1186159 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[43]} 0 2
   [junit4]   2> 1186160 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[44]} 0 0
   [junit4]   2> 1186160 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[45]} 0 0
   [junit4]   2> 1186160 INFO  (qtp439187683-8761) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[46]} 0 0
   [junit4]   2> 1186161 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[47]} 0 0
   [junit4]   2> 1186162 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[48]} 0 0
   [junit4]   2> 1186163 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[49]} 0 0
   [junit4]   2> 1186163 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[50]} 0 0
   [junit4]   2> 1186164 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[51]} 0 0
   [junit4]   2> 1186165 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[52]} 0 0
   [junit4]   2> 1186166 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[53]} 0 0
   [junit4]   2> 1186168 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[54]} 0 2
   [junit4]   2> 1186169 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[55]} 0 0
   [junit4]   2> 1186169 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[56]} 0 0
   [junit4]   2> 1186170 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[57]} 0 0
   [junit4]   2> 1186170 INFO  (qtp439187683-8754) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[58]} 0 0
   [junit4]   2> 1186170 INFO  (qtp439187683-8759) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[59]} 0 0
   [junit4]   2> 1186171 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[60]} 0 0
   [junit4]   2> 1186171 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[61]} 0 0
   [junit4]   2> 1186172 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[62]} 0 0
   [junit4]   2> 1186172 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[63]} 0 0
   [junit4]   2> 1186172 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[64]} 0 0
   [junit4]   2> 1186175 INFO  (qtp439187683-8755) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[65]} 0 2
   [junit4]   2> 1186175 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[66]} 0 0
   [junit4]   2> 1186176 INFO  (qtp439187683-8756) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[67]} 0 0
   [junit4]   2> 1186176 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[68]} 0 0
   [junit4]   2> 1186177 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[69]} 0 0
   [junit4]   2> 1186177 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[70]} 0 0
   [junit4]   2> 1186177 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[71]} 0 0
   [junit4]   2> 1186178 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[72]} 0 0
   [junit4]   2> 1186178 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[73]} 0 0
   [junit4]   2> 1186179 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[74]} 0 0
   [junit4]   2> 1186179 INFO  (qtp439187683-8758) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[75]} 0 0
   [junit4]   2> 1186182 INFO  (qtp439187683-8758) [    x:

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

s=0 QTime=0
   [junit4]   2> 1267067 INFO  (indexFetcher-5008-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1267067 INFO  (indexFetcher-5008-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485228577566
   [junit4]   2> 1267067 INFO  (indexFetcher-5008-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 1267068 INFO  (indexFetcher-5008-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 1267068 INFO  (Thread-2492) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=56.29296875, 
floorSegmentMB=0.474609375, forceMergeDeletesPctAllowed=16.35803033493523, 
segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1267069 INFO  (Thread-2492) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1267070 INFO  (Thread-2492) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@72ff9517[collection1] main]
   [junit4]   2> 1267070 INFO  (Thread-2492) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@38c949a6
   [junit4]   2> 1267071 INFO  
(searcherExecutor-5004-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@72ff9517[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
   [junit4]   2> 1267072 INFO  (Thread-2492) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1267088 INFO  (qtp1298531011-9743) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 1267088 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.h.TestReplicationHandler Waited for 300ms and found 1 docs
   [junit4]   2> 1267089 INFO  (qtp728390470-9725) [    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> 1267090 INFO  (qtp728390470-9721) [    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> 1267091 INFO  (qtp728390470-9727) [    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> 1267092 INFO  (qtp1298531011-9743) [    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> 1267092 INFO  (qtp728390470-9724) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1267092 INFO  (qtp1298531011-9751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1267093 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending doTestIndexAndConfigReplication
   [junit4]   2> 1267103 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.AbstractConnector Stopped 
ServerConnector@218c68b0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1267103 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=191716058
   [junit4]   2> 1267104 INFO  (coreCloseExecutor-5009-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5f8d0e67
   [junit4]   2> 1267104 INFO  (coreCloseExecutor-5009-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1267105 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1267105 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@83fe836{/solr,null,UNAVAILABLE}
   [junit4]   2> 1267125 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.AbstractConnector Stopped 
ServerConnector@26c29fc4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1267125 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=105289717
   [junit4]   2> 1267126 INFO  (coreCloseExecutor-5011-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3b3467dc
   [junit4]   2> 1267137 INFO  (coreCloseExecutor-5011-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1267146 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1267146 INFO  
(TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[B6D340F54DF7ED2F])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1de90358{/solr,null,UNAVAILABLE}
   [junit4]   2> 1267156 INFO  
(SUITE-TestReplicationHandler-seed#[B6D340F54DF7ED2F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_B6D340F54DF7ED2F-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{name=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
newname=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))},
 docValues:{}, maxPointsInLeafNode=1977, maxMBSortInHeap=6.229491111302826, 
sim=RandomSimilarity(queryNorm=false): {}, locale=el-GR, timezone=Europe/Kirov
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 
1.8.0_102 (64-bit)/cpus=4,threads=1,free=138151456,total=533725184
   [junit4]   2> NOTE: All tests run in this JVM: [CoreAdminCreateDiscoverTest, 
HdfsDirectoryTest, RankQueryTest, StatelessScriptUpdateProcessorFactoryTest, 
TestCustomStream, SolrIndexConfigTest, TestSubQueryTransformerCrossCore, 
TestIndexSearcher, PeerSyncWithIndexFingerprintCachingTest, 
XmlUpdateRequestHandlerTest, MBeansHandlerTest, 
ConjunctionSolrSpellCheckerTest, TestInfoStreamLogging, 
DistributedSuggestComponentTest, BasicAuthIntegrationTest, 
SharedFSAutoReplicaFailoverTest, RulesTest, TestIBSimilarityFactory, 
TestSchemaVersionResource, TestReload, ShowFileRequestHandlerTest, 
TestRandomRequestDistribution, TestComplexPhraseQParserPlugin, TestConfig, 
TestReqParamsAPI, UpdateParamsTest, FieldAnalysisRequestHandlerTest, 
URLClassifyProcessorTest, ConnectionManagerTest, TestFastOutputStream, 
PreAnalyzedFieldTest, TestRequestForwarding, CircularListTest, TestZkChroot, 
WrapperMergePolicyFactoryTest, OverseerCollectionConfigSetProcessorTest, 
UnloadDistributedZkTest, BasicDistributedZk2Test, TestFieldCacheWithThreads, 
TestSolrQueryResponse, JvmMetricsTest, TestLuceneMatchVersion, 
BinaryUpdateRequestHandlerTest, TestPushWriter, ExternalFileFieldSortTest, 
SolrCLIZkUtilsTest, TestManagedSynonymFilterFactory, 
TestBackupRepositoryFactory, EchoParamsTest, TestComponentsName, 
TestAuthorizationFramework, DebugComponentTest, ChangedSchemaMergeTest, 
InfoHandlerTest, TestSolrJ, ExplicitHLLTest, AutoCommitTest, 
TestConfigSetsAPIExclusivity, TestLeaderElectionZkExpiry, 
HdfsTlogReplayBufferedWhileIndexingTest, LeaderFailoverAfterPartitionTest, 
SuggesterTSTTest, PrimUtilsTest, TestLockTree, TestManagedSchema, 
SuggesterWFSTTest, CurrencyFieldXmlFileTest, HLLUtilTest, DistributedQueueTest, 
TestSQLHandlerNonCloud, TestQuerySenderNoQuery, TestUseDocValuesAsStored, 
TestTolerantSearch, NoCacheHeaderTest, BlockJoinFacetRandomTest, 
SynonymTokenizerTest, ZkStateWriterTest, TestFileDictionaryLookup, 
RequestHandlersTest, TestPHPSerializedResponseWriter, TestMergePolicyConfig, 
AnalysisErrorHandlingTest, LukeRequestHandlerTest, DeleteReplicaTest, 
TestSegmentSorting, TestBinaryResponseWriter, AnalyticsQueryTest, 
TestSolrDeletionPolicy1, TestCoreDiscovery, CoreAdminHandlerTest, 
TestExactSharedStatsCache, TestDistributedMissingSort, 
CachingDirectoryFactoryTest, TestQueryWrapperFilter, TestSolrCoreSnapshots, 
SpatialRPTFieldTypeTest, SolrGraphiteReporterTest, 
TestTolerantUpdateProcessorRandomCloud, TestStandardQParsers, 
TestFieldResource, ExitableDirectoryReaderTest, TestWriterPerf, 
TestReRankQParserPlugin, DistribCursorPagingTest, SliceStateTest, 
OutputWriterTest, SSLMigrationTest, TestSolrDynamicMBean, 
ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, 
OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, 
ShardRoutingTest, TestReplicationHandler]
   [junit4] Completed [356/680 (1!)] on J2 in 95.52s, 15 tests, 1 error, 1 
skipped <<< FAILURES!

[...truncated 63443 lines...]



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org